builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-019 starttime: 1494870034.7 results: success (0) revision: 241a875b457fd4164cfe96f57865dd0969f20909 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 10:40:34.704474) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 10:40:34.704835) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 10:40:34.705345) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 10:40:34.797037) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:40:34.797298) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-15 10:40:35-- 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% 37.7M=0s 2017-05-15 10:40:35 (37.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.165430 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:40:35.005111) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:40:35.005407) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019404 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:40:35.054074) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:40:35.054362) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 241a875b457fd4164cfe96f57865dd0969f20909 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 241a875b457fd4164cfe96f57865dd0969f20909 --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=78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-15 10:40:35,285 truncating revision to first 12 chars 2017-05-15 10:40:35,285 Setting DEBUG logging. 2017-05-15 10:40:35,285 attempt 1/10 2017-05-15 10:40:35,285 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/241a875b457f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-15 10:40:35,607 unpacking tar archive at: mozilla-beta-241a875b457f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.688895 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:40:35.758502) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 10:40:35.761783) ========= script_repo_revision: 241a875b457fd4164cfe96f57865dd0969f20909 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 10:40:35.762136) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 10:40:35.762397) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 10:40:35.772058) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-05-15 10:40:35.772321) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO'] 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=78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:40:36 INFO - MultiFileLogger online at 20170515 10:40:36 in /builds/slave/test 10:40:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 10:40:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:40:36 INFO - {'append_to_log': False, 10:40:36 INFO - 'base_work_dir': '/builds/slave/test', 10:40:36 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 10:40:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:40:36 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 10:40:36 INFO - 'buildbot_json_path': 'buildprops.json', 10:40:36 INFO - 'config_files': ('talos/linux_config.py',), 10:40:36 INFO - 'default_actions': ('clobber', 10:40:36 INFO - 'read-buildbot-config', 10:40:36 INFO - 'download-and-extract', 10:40:36 INFO - 'populate-webroot', 10:40:36 INFO - 'create-virtualenv', 10:40:36 INFO - 'install', 10:40:36 INFO - 'run-tests'), 10:40:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:40:36 INFO - 'download_minidump_stackwalk': True, 10:40:36 INFO - 'download_symbols': 'ondemand', 10:40:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:40:36 INFO - 'tooltool.py': '/tools/tooltool.py', 10:40:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:40:36 INFO - '/tools/misc-python/virtualenv.py')}, 10:40:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:40:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:40:36 INFO - 'gecko_profile': False, 10:40:36 INFO - 'gecko_profile_interval': 0, 10:40:36 INFO - 'installer_path': 'installer.exe', 10:40:36 INFO - 'log_level': 'info', 10:40:36 INFO - 'log_name': 'talos', 10:40:36 INFO - 'log_to_console': True, 10:40:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:40:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:40:36 INFO - 'opt_config_files': (), 10:40:36 INFO - 'pip_index': False, 10:40:36 INFO - 'suite': 'dromaeojs', 10:40:36 INFO - 'system_bits': '32', 10:40:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:40:36 INFO - 'title': 'talos-linux64-ix-019', 10:40:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:40:36 INFO - 'use_talos_json': True, 10:40:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:40:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:40:36 INFO - 'work_dir': 'build'} 10:40:36 INFO - [mozharness: 2017-05-15 17:40:36.176599Z] Running clobber step. 10:40:36 INFO - Running pre-action listener: _resource_record_pre_action 10:40:36 INFO - Running main action method: clobber 10:40:36 INFO - rmtree: /builds/slave/test/build 10:40:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:40:37 INFO - Running post-action listener: _resource_record_post_action 10:40:37 INFO - [mozharness: 2017-05-15 17:40:37.144450Z] Finished clobber step (success) 10:40:37 INFO - [mozharness: 2017-05-15 17:40:37.144550Z] Running read-buildbot-config step. 10:40:37 INFO - Running pre-action listener: _resource_record_pre_action 10:40:37 INFO - Running main action method: read_buildbot_config 10:40:37 INFO - Using buildbot properties: 10:40:37 INFO - { 10:40:37 INFO - "project": "", 10:40:37 INFO - "product": "firefox", 10:40:37 INFO - "who": "ryanvm@gmail.com", 10:40:37 INFO - "installer_path": "public/build/target.tar.bz2", 10:40:37 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 10:40:37 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 10:40:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 10:40:37 INFO - "stage_platform": "linux64", 10:40:37 INFO - "basedir": "/builds/slave/test", 10:40:37 INFO - "buildnumber": 43, 10:40:37 INFO - "platform": "ubuntu64_hw", 10:40:37 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:40:37 INFO - "slavebuilddir": "test", 10:40:37 INFO - "taskId": "eYYALOiGS_mvAh-OHiqOxA", 10:40:37 INFO - "branch": "mozilla-beta", 10:40:37 INFO - "script_repo_revision": "production", 10:40:37 INFO - "revision": "241a875b457fd4164cfe96f57865dd0969f20909", 10:40:37 INFO - "slavename": "talos-linux64-ix-019", 10:40:37 INFO - "repo_path": "releases/mozilla-beta" 10:40:37 INFO - } 10:40:37 INFO - Finding installer, test and symbols from parent task. 10:40:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eYYALOiGS_mvAh-OHiqOxA'}, attempt #1 10:40:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eYYALOiGS_mvAh-OHiqOxA'}, attempt #1 10:40:38 INFO - Task dependencies: fcyPWT3kTbqACWVJQfWavQ 10:40:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ'}, attempt #1 10:40:38 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.tar.bz2 10:40:38 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/test_packages.json 10:40:38 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.crashreporter-symbols.zip 10:40:38 INFO - Running post-action listener: _resource_record_post_action 10:40:38 INFO - [mozharness: 2017-05-15 17:40:38.537682Z] Finished read-buildbot-config step (success) 10:40:38 INFO - [mozharness: 2017-05-15 17:40:38.537900Z] Running download-and-extract step. 10:40:38 INFO - Running pre-action listener: _resource_record_pre_action 10:40:38 INFO - Running main action method: download_and_extract 10:40:38 INFO - mkdir: /builds/slave/test/build/tests 10:40:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:40:38 INFO - trying https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.test_packages.json 10:40:38 INFO - Downloading https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 10:40:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 10:40:39 INFO - Downloaded 986 bytes. 10:40:39 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 10:40:39 INFO - Using the following test package requirements: 10:40:39 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 10:40:39 INFO - u'common': [u'target.common.tests.zip'], 10:40:39 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 10:40:39 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 10:40:39 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 10:40:39 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 10:40:39 INFO - u'mozbase': [u'target.common.tests.zip'], 10:40:39 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 10:40:39 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 10:40:39 INFO - u'web-platform': [u'target.common.tests.zip', 10:40:39 INFO - u'target.web-platform.tests.zip'], 10:40:39 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 10:40:39 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 10:40:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.common.tests.zip 10:40:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:40:39 INFO - Fetch https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.common.tests.zip into memory 10:40:41 INFO - Expected file size: 59543038 10:40:41 INFO - Obtained file size: 59543038 10:40:50 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 10:40:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.common.tests.zip 10:40:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 10:40:50 INFO - Fetch https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.common.tests.zip into memory 10:40:52 INFO - Expected file size: 59543038 10:40:52 INFO - Obtained file size: 59543038 10:41:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.talos.tests.zip 10:41:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 10:41:03 INFO - Fetch https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.talos.tests.zip into memory 10:41:04 INFO - Expected file size: 14404449 10:41:04 INFO - Obtained file size: 14404449 10:41:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:05 INFO - trying https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.tar.bz2 10:41:05 INFO - Downloading https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 10:41:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 10:41:07 INFO - Downloaded 59777224 bytes. 10:41:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.tar.bz2 10:41:07 INFO - mkdir: /builds/slave/test/properties 10:41:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:41:07 INFO - Writing to file /builds/slave/test/properties/build_url 10:41:07 INFO - Contents: 10:41:07 INFO - build_url:https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.tar.bz2 10:41:07 INFO - Running post-action listener: _resource_record_post_action 10:41:07 INFO - Running post-action listener: set_extra_try_arguments 10:41:07 INFO - [mozharness: 2017-05-15 17:41:07.841259Z] Finished download-and-extract step (success) 10:41:07 INFO - [mozharness: 2017-05-15 17:41:07.841401Z] Running populate-webroot step. 10:41:07 INFO - Running pre-action listener: _resource_record_pre_action 10:41:07 INFO - Running main action method: populate_webroot 10:41:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:41:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:41:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:41:07 INFO - '--tppagecycles', 10:41:07 INFO - '7'], 10:41:07 INFO - 'tests': ['tsvgm']}}, 10:41:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:41:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:41:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:41:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:41:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:41:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:41:07 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:41:07 INFO - 'talos_options': ['--disable-e10s'], 10:41:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:41:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:41:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:41:07 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:41:07 INFO - 'talos_options': ['--disable-e10s'], 10:41:07 INFO - 'tests': ['damp', 'tps']}, 10:41:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:41:07 INFO - 'tests': ['damp', 'tps']}, 10:41:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:41:07 INFO - 'tests': ['dromaeo_dom']}, 10:41:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:41:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:41:07 INFO - 'tests': ['basic_compositor_video']}, 10:41:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:41:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:41:07 INFO - 'tests': ['a11yr', 10:41:07 INFO - 'ts_paint', 10:41:07 INFO - 'tpaint', 10:41:07 INFO - 'sessionrestore', 10:41:07 INFO - 'sessionrestore_no_auto_restore', 10:41:07 INFO - 'tabpaint']}, 10:41:07 INFO - 'other-e10s': {'tests': ['a11yr', 10:41:07 INFO - 'ts_paint', 10:41:07 INFO - 'tpaint', 10:41:07 INFO - 'sessionrestore', 10:41:07 INFO - 'sessionrestore_no_auto_restore', 10:41:07 INFO - 'tabpaint']}, 10:41:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:41:07 INFO - 'ts_paint', 10:41:07 INFO - 'tpaint', 10:41:07 INFO - 'sessionrestore', 10:41:07 INFO - 'sessionrestore_no_auto_restore', 10:41:07 INFO - 'tabpaint']}, 10:41:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:41:07 INFO - 'ts_paint', 10:41:07 INFO - 'tpaint', 10:41:07 INFO - 'sessionrestore', 10:41:07 INFO - 'sessionrestore_no_auto_restore', 10:41:07 INFO - 'tabpaint']}, 10:41:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:41:07 INFO - 'tests': ['a11yr', 10:41:07 INFO - 'ts_paint', 10:41:07 INFO - 'tpaint', 10:41:07 INFO - 'sessionrestore', 10:41:07 INFO - 'sessionrestore_no_auto_restore', 10:41:07 INFO - 'tabpaint']}, 10:41:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:41:07 INFO - 'tests': ['a11yr', 10:41:07 INFO - 'ts_paint', 10:41:07 INFO - 'tpaint', 10:41:07 INFO - 'sessionrestore', 10:41:07 INFO - 'sessionrestore_no_auto_restore', 10:41:07 INFO - 'tabpaint']}, 10:41:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:41:07 INFO - 'tests': ['tsvgx', 10:41:07 INFO - 'tsvgr_opacity', 10:41:07 INFO - 'tart', 10:41:07 INFO - 'tscrollx', 10:41:07 INFO - 'cart', 10:41:07 INFO - 'tsvg_static']}, 10:41:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:41:07 INFO - 'tsvgr_opacity', 10:41:07 INFO - 'tart', 10:41:07 INFO - 'tscrollx', 10:41:07 INFO - 'cart', 10:41:07 INFO - 'tsvg_static']}, 10:41:07 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:41:07 INFO - 'talos_options': ['--disable-e10s'], 10:41:07 INFO - 'tests': ['tp5o']}, 10:41:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:41:07 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:41:07 INFO - 'talos_options': ['--disable-e10s', 10:41:07 INFO - '--xperf_path', 10:41:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:41:07 INFO - 'tests': ['tp5n']}, 10:41:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:41:07 INFO - 'talos_options': ['--xperf_path', 10:41:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:41:07 INFO - 'tests': ['tp5n']}}, 10:41:07 INFO - 'talos.zip': {'path': '', 10:41:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:41:07 INFO - Running post-action listener: _resource_record_post_action 10:41:07 INFO - [mozharness: 2017-05-15 17:41:07.854617Z] Finished populate-webroot step (success) 10:41:07 INFO - [mozharness: 2017-05-15 17:41:07.854707Z] Running create-virtualenv step. 10:41:07 INFO - Running pre-action listener: _resource_record_pre_action 10:41:07 INFO - Running main action method: create_virtualenv 10:41:07 INFO - Creating virtualenv /builds/slave/test/build/venv 10:41:07 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 10:41:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:41:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:41:08 INFO - Using real prefix '/usr' 10:41:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:41:08 INFO - Installing distribute.............................................................................................................................................................................................done. 10:41:10 INFO - Installing pip.................done. 10:41:10 INFO - Return code: 0 10:41:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:41:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:41:10 INFO - Reading from file tmpfile_stdout 10:41:10 INFO - Output received: 10:41:10 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) 10:41:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:41:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', '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 10:41:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:41:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:41:10 INFO - Using env: {'DISPLAY': ':0', 10:41:10 INFO - 'HOME': '/home/cltbld', 10:41:10 INFO - 'LANG': 'en_US.UTF-8', 10:41:10 INFO - 'LANGUAGE': 'en_US:en', 10:41:10 INFO - 'LOGNAME': 'cltbld', 10:41:10 INFO - 'MAIL': '/var/mail/cltbld', 10:41:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:10 INFO - 'MOZ_NO_REMOTE': '1', 10:41:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:10 INFO - 'NO_EM_RESTART': '1', 10:41:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:10 INFO - 'PWD': '/builds/slave/test', 10:41:10 INFO - 'SHELL': '/bin/bash', 10:41:10 INFO - 'SHLVL': '1', 10:41:10 INFO - 'TERM': 'linux', 10:41:10 INFO - 'TMOUT': '86400', 10:41:10 INFO - 'USER': 'cltbld', 10:41:10 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', 10:41:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:10 INFO - '_': '/tools/buildbot/bin/python'} 10:41:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:11 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 10:41:11 INFO - Cleaning up... 10:41:11 INFO - Return code: 0 10:41:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:41:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', '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 10:41:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:41:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:41:11 INFO - Using env: {'DISPLAY': ':0', 10:41:11 INFO - 'HOME': '/home/cltbld', 10:41:11 INFO - 'LANG': 'en_US.UTF-8', 10:41:11 INFO - 'LANGUAGE': 'en_US:en', 10:41:11 INFO - 'LOGNAME': 'cltbld', 10:41:11 INFO - 'MAIL': '/var/mail/cltbld', 10:41:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:11 INFO - 'MOZ_NO_REMOTE': '1', 10:41:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:11 INFO - 'NO_EM_RESTART': '1', 10:41:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:11 INFO - 'PWD': '/builds/slave/test', 10:41:11 INFO - 'SHELL': '/bin/bash', 10:41:11 INFO - 'SHLVL': '1', 10:41:11 INFO - 'TERM': 'linux', 10:41:11 INFO - 'TMOUT': '86400', 10:41:11 INFO - 'USER': 'cltbld', 10:41:11 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', 10:41:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:11 INFO - '_': '/tools/buildbot/bin/python'} 10:41:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:11 INFO - Downloading/unpacking psutil>=3.1.1 10:41:11 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 10:41:11 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 10:41:11 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 10:41:11 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 10:41:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:41:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:41:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:41:12 INFO - Installing collected packages: psutil 10:41:12 INFO - Running setup.py install for psutil 10:41:12 INFO - building 'psutil._psutil_linux' extension 10:41:12 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 10:41:13 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 10:41:13 INFO - building 'psutil._psutil_posix' extension 10:41:13 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 10:41:13 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 10:41:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:41:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:41:13 INFO - Successfully installed psutil 10:41:13 INFO - Cleaning up... 10:41:13 INFO - Return code: 0 10:41:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:41:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', '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 10:41:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:41:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:41:13 INFO - Using env: {'DISPLAY': ':0', 10:41:13 INFO - 'HOME': '/home/cltbld', 10:41:13 INFO - 'LANG': 'en_US.UTF-8', 10:41:13 INFO - 'LANGUAGE': 'en_US:en', 10:41:13 INFO - 'LOGNAME': 'cltbld', 10:41:13 INFO - 'MAIL': '/var/mail/cltbld', 10:41:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:13 INFO - 'MOZ_NO_REMOTE': '1', 10:41:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:13 INFO - 'NO_EM_RESTART': '1', 10:41:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:13 INFO - 'PWD': '/builds/slave/test', 10:41:13 INFO - 'SHELL': '/bin/bash', 10:41:13 INFO - 'SHLVL': '1', 10:41:13 INFO - 'TERM': 'linux', 10:41:13 INFO - 'TMOUT': '86400', 10:41:13 INFO - 'USER': 'cltbld', 10:41:13 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', 10:41:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:13 INFO - '_': '/tools/buildbot/bin/python'} 10:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:41:14 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 10:41:14 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 10:41:14 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 10:41:14 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 10:41:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:41:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:41:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:41:16 INFO - Installing collected packages: mozsystemmonitor 10:41:16 INFO - Running setup.py install for mozsystemmonitor 10:41:16 INFO - Successfully installed mozsystemmonitor 10:41:16 INFO - Cleaning up... 10:41:16 INFO - Return code: 0 10:41:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:41:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', '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 10:41:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:41:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:41:16 INFO - Using env: {'DISPLAY': ':0', 10:41:16 INFO - 'HOME': '/home/cltbld', 10:41:16 INFO - 'LANG': 'en_US.UTF-8', 10:41:16 INFO - 'LANGUAGE': 'en_US:en', 10:41:16 INFO - 'LOGNAME': 'cltbld', 10:41:16 INFO - 'MAIL': '/var/mail/cltbld', 10:41:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:16 INFO - 'MOZ_NO_REMOTE': '1', 10:41:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:16 INFO - 'NO_EM_RESTART': '1', 10:41:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:16 INFO - 'PWD': '/builds/slave/test', 10:41:16 INFO - 'SHELL': '/bin/bash', 10:41:16 INFO - 'SHLVL': '1', 10:41:16 INFO - 'TERM': 'linux', 10:41:16 INFO - 'TMOUT': '86400', 10:41:16 INFO - 'USER': 'cltbld', 10:41:16 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', 10:41:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:16 INFO - '_': '/tools/buildbot/bin/python'} 10:41:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:16 INFO - Downloading/unpacking jsonschema==2.5.1 10:41:16 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 10:41:16 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 10:41:16 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 10:41:16 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 10:41:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:41:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:41:17 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 10:41:17 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 10:41:17 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 10:41:17 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 10:41:17 INFO - Downloading functools32-3.2.3-2.tar.gz 10:41:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:41:17 INFO - warning: no files found matching '*.txt' 10:41:17 INFO - no previously-included directories found matching 'build' 10:41:17 INFO - no previously-included directories found matching 'dist' 10:41:17 INFO - no previously-included directories found matching '.git*' 10:41:17 INFO - Installing collected packages: jsonschema, functools32 10:41:17 INFO - Running setup.py install for functools32 10:41:18 INFO - warning: no files found matching '*.txt' 10:41:18 INFO - no previously-included directories found matching 'build' 10:41:18 INFO - no previously-included directories found matching 'dist' 10:41:18 INFO - no previously-included directories found matching '.git*' 10:41:18 INFO - Successfully installed jsonschema functools32 10:41:18 INFO - Cleaning up... 10:41:18 INFO - Return code: 0 10:41:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', '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 10:41:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:41:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:41:18 INFO - Using env: {'DISPLAY': ':0', 10:41:18 INFO - 'HOME': '/home/cltbld', 10:41:18 INFO - 'LANG': 'en_US.UTF-8', 10:41:18 INFO - 'LANGUAGE': 'en_US:en', 10:41:18 INFO - 'LOGNAME': 'cltbld', 10:41:18 INFO - 'MAIL': '/var/mail/cltbld', 10:41:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:18 INFO - 'MOZ_NO_REMOTE': '1', 10:41:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:18 INFO - 'NO_EM_RESTART': '1', 10:41:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:18 INFO - 'PWD': '/builds/slave/test', 10:41:18 INFO - 'SHELL': '/bin/bash', 10:41:18 INFO - 'SHLVL': '1', 10:41:18 INFO - 'TERM': 'linux', 10:41:18 INFO - 'TMOUT': '86400', 10:41:18 INFO - 'USER': 'cltbld', 10:41:18 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', 10:41:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:18 INFO - '_': '/tools/buildbot/bin/python'} 10:41:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:41:18 INFO - Cleaning up... 10:41:18 INFO - Return code: 0 10:41:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', '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 10:41:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:41:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:41:18 INFO - Using env: {'DISPLAY': ':0', 10:41:18 INFO - 'HOME': '/home/cltbld', 10:41:18 INFO - 'LANG': 'en_US.UTF-8', 10:41:18 INFO - 'LANGUAGE': 'en_US:en', 10:41:18 INFO - 'LOGNAME': 'cltbld', 10:41:18 INFO - 'MAIL': '/var/mail/cltbld', 10:41:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:18 INFO - 'MOZ_NO_REMOTE': '1', 10:41:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:18 INFO - 'NO_EM_RESTART': '1', 10:41:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:18 INFO - 'PWD': '/builds/slave/test', 10:41:18 INFO - 'SHELL': '/bin/bash', 10:41:18 INFO - 'SHLVL': '1', 10:41:18 INFO - 'TERM': 'linux', 10:41:18 INFO - 'TMOUT': '86400', 10:41:18 INFO - 'USER': 'cltbld', 10:41:18 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', 10:41:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:18 INFO - '_': '/tools/buildbot/bin/python'} 10:41:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:18 INFO - Downloading/unpacking blobuploader==1.2.4 10:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:19 INFO - Downloading blobuploader-1.2.4.tar.gz 10:41:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:41:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:41:19 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 10:41:19 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 10:41:19 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 10:41:19 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 10:41:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:41:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:20 INFO - Downloading docopt-0.6.1.tar.gz 10:41:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:41:20 INFO - Installing collected packages: blobuploader, requests, docopt 10:41:20 INFO - Running setup.py install for blobuploader 10:41:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:41:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:41:20 INFO - Running setup.py install for requests 10:41:20 INFO - Running setup.py install for docopt 10:41:20 INFO - Successfully installed blobuploader requests docopt 10:41:20 INFO - Cleaning up... 10:41:20 INFO - Return code: 0 10:41:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:41:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', '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 10:41:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:41:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:41:20 INFO - Using env: {'DISPLAY': ':0', 10:41:20 INFO - 'HOME': '/home/cltbld', 10:41:20 INFO - 'LANG': 'en_US.UTF-8', 10:41:20 INFO - 'LANGUAGE': 'en_US:en', 10:41:20 INFO - 'LOGNAME': 'cltbld', 10:41:20 INFO - 'MAIL': '/var/mail/cltbld', 10:41:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:20 INFO - 'MOZ_NO_REMOTE': '1', 10:41:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:20 INFO - 'NO_EM_RESTART': '1', 10:41:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:20 INFO - 'PWD': '/builds/slave/test', 10:41:20 INFO - 'SHELL': '/bin/bash', 10:41:20 INFO - 'SHLVL': '1', 10:41:20 INFO - 'TERM': 'linux', 10:41:20 INFO - 'TMOUT': '86400', 10:41:20 INFO - 'USER': 'cltbld', 10:41:20 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', 10:41:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:20 INFO - '_': '/tools/buildbot/bin/python'} 10:41:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:41:21 INFO - Running setup.py (path:/tmp/pip-EcZiHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:41:21 INFO - Running setup.py (path:/tmp/pip-kYwZBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:41:21 INFO - Running setup.py (path:/tmp/pip-bjIWMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:41:21 INFO - Running setup.py (path:/tmp/pip-SWcOtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:41:21 INFO - Running setup.py (path:/tmp/pip-JeC7Kc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:41:21 INFO - Running setup.py (path:/tmp/pip-rdNbFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:41:21 INFO - Running setup.py (path:/tmp/pip-RKCzhi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:41:21 INFO - Running setup.py (path:/tmp/pip-DYbeYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:41:21 INFO - Running setup.py (path:/tmp/pip-9LmUWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:41:21 INFO - Running setup.py (path:/tmp/pip-cacBVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:41:21 INFO - Running setup.py (path:/tmp/pip-gOYSrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:41:21 INFO - Running setup.py (path:/tmp/pip-c7TTXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:41:21 INFO - Running setup.py (path:/tmp/pip-amnbBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:41:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:41:21 INFO - Running setup.py (path:/tmp/pip-U8b1vK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:22 INFO - Running setup.py (path:/tmp/pip-9W5VJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:41:22 INFO - Running setup.py (path:/tmp/pip-P_n_pY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:41:22 INFO - Running setup.py (path:/tmp/pip-klKgaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:41:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:41:22 INFO - Running setup.py install for manifestparser 10:41:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:41:22 INFO - Running setup.py install for mozcrash 10:41:22 INFO - Running setup.py install for mozdebug 10:41:22 INFO - Running setup.py install for mozdevice 10:41:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:41:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:41:22 INFO - Running setup.py install for mozfile 10:41:22 INFO - Running setup.py install for mozhttpd 10:41:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:41:22 INFO - Running setup.py install for mozinfo 10:41:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:41:22 INFO - Running setup.py install for mozInstall 10:41:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:41:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:41:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:41:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:41:23 INFO - Running setup.py install for mozleak 10:41:23 INFO - Running setup.py install for mozlog 10:41:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:41:23 INFO - Running setup.py install for moznetwork 10:41:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:41:23 INFO - Running setup.py install for mozprocess 10:41:23 INFO - Running setup.py install for mozprofile 10:41:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:41:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:41:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:41:23 INFO - Running setup.py install for mozrunner 10:41:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:41:23 INFO - Running setup.py install for mozscreenshot 10:41:23 INFO - Running setup.py install for moztest 10:41:23 INFO - Running setup.py install for mozversion 10:41:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:41:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:41:24 INFO - Cleaning up... 10:41:24 INFO - Return code: 0 10:41:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:41:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', '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 10:41:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:41:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:41:24 INFO - Using env: {'DISPLAY': ':0', 10:41:24 INFO - 'HOME': '/home/cltbld', 10:41:24 INFO - 'LANG': 'en_US.UTF-8', 10:41:24 INFO - 'LANGUAGE': 'en_US:en', 10:41:24 INFO - 'LOGNAME': 'cltbld', 10:41:24 INFO - 'MAIL': '/var/mail/cltbld', 10:41:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:24 INFO - 'MOZ_NO_REMOTE': '1', 10:41:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:24 INFO - 'NO_EM_RESTART': '1', 10:41:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:24 INFO - 'PWD': '/builds/slave/test', 10:41:24 INFO - 'SHELL': '/bin/bash', 10:41:24 INFO - 'SHLVL': '1', 10:41:24 INFO - 'TERM': 'linux', 10:41:24 INFO - 'TMOUT': '86400', 10:41:24 INFO - 'USER': 'cltbld', 10:41:24 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', 10:41:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:24 INFO - '_': '/tools/buildbot/bin/python'} 10:41:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:41:24 INFO - Running setup.py (path:/tmp/pip-W8JCbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:41:24 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)) 10:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:41:24 INFO - Running setup.py (path:/tmp/pip-ce9Ffp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:41:24 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)) 10:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:41:24 INFO - Running setup.py (path:/tmp/pip-pTpsbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:41:24 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)) 10:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:41:24 INFO - Running setup.py (path:/tmp/pip-n2ZRqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:41:24 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)) 10:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:41:24 INFO - Running setup.py (path:/tmp/pip-tZtX7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:41:24 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)) 10:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:41:24 INFO - Running setup.py (path:/tmp/pip-8AF5bz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:41:24 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)) 10:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:41:24 INFO - Running setup.py (path:/tmp/pip-79BgrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:41:24 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)) 10:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:41:24 INFO - Running setup.py (path:/tmp/pip-ul6S7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:41:24 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)) 10:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:41:24 INFO - Running setup.py (path:/tmp/pip-7__R8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:41:24 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)) 10:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:41:24 INFO - Running setup.py (path:/tmp/pip-8xg25R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:41:24 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)) 10:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:41:24 INFO - Running setup.py (path:/tmp/pip-GxBwjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:41:25 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)) 10:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:41:25 INFO - Running setup.py (path:/tmp/pip-b_FwCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:41:25 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)) 10:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:41:25 INFO - Running setup.py (path:/tmp/pip-cpUYv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:41:25 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)) 10:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:41:25 INFO - Running setup.py (path:/tmp/pip-d5b7YL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:41:25 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)) 10:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:25 INFO - Running setup.py (path:/tmp/pip-njVN2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:25 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)) 10:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:41:25 INFO - Running setup.py (path:/tmp/pip-dYMpw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:41:25 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)) 10:41:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:41:25 INFO - Running setup.py (path:/tmp/pip-ZNFSjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:41:25 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)) 10:41:25 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)) 10:41:25 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)) 10:41:25 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)) 10:41:25 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)) 10:41:25 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)) 10:41:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:26 INFO - Downloading blessings-1.6.tar.gz 10:41:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:41:26 INFO - Installing collected packages: blessings 10:41:26 INFO - Running setup.py install for blessings 10:41:26 INFO - Successfully installed blessings 10:41:26 INFO - Cleaning up... 10:41:26 INFO - Return code: 0 10:41:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:41:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:41:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:41:27 INFO - Reading from file tmpfile_stdout 10:41:27 INFO - Current package versions: 10:41:27 INFO - argparse == 1.2.1 10:41:27 INFO - blessings == 1.6 10:41:27 INFO - blobuploader == 1.2.4 10:41:27 INFO - docopt == 0.6.1 10:41:27 INFO - functools32 == 3.2.3-2 10:41:27 INFO - jsonschema == 2.5.1 10:41:27 INFO - manifestparser == 1.1 10:41:27 INFO - mozInstall == 1.12 10:41:27 INFO - mozcrash == 1.0 10:41:27 INFO - mozdebug == 0.1 10:41:27 INFO - mozdevice == 0.49 10:41:27 INFO - mozfile == 1.2 10:41:27 INFO - mozhttpd == 0.7 10:41:27 INFO - mozinfo == 0.9 10:41:27 INFO - mozleak == 0.1 10:41:27 INFO - mozlog == 3.4 10:41:27 INFO - moznetwork == 0.27 10:41:27 INFO - mozprocess == 0.25 10:41:27 INFO - mozprofile == 0.28 10:41:27 INFO - mozrunner == 6.13 10:41:27 INFO - mozscreenshot == 0.1 10:41:27 INFO - mozsystemmonitor == 0.3 10:41:27 INFO - moztest == 0.8 10:41:27 INFO - mozversion == 1.4 10:41:27 INFO - psutil == 3.1.1 10:41:27 INFO - requests == 1.2.3 10:41:27 INFO - wsgiref == 0.1.2 10:41:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:41:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', '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 10:41:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:41:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:41:27 INFO - Using env: {'DISPLAY': ':0', 10:41:27 INFO - 'HOME': '/home/cltbld', 10:41:27 INFO - 'LANG': 'en_US.UTF-8', 10:41:27 INFO - 'LANGUAGE': 'en_US:en', 10:41:27 INFO - 'LOGNAME': 'cltbld', 10:41:27 INFO - 'MAIL': '/var/mail/cltbld', 10:41:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:27 INFO - 'MOZ_NO_REMOTE': '1', 10:41:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:27 INFO - 'NO_EM_RESTART': '1', 10:41:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:27 INFO - 'PWD': '/builds/slave/test', 10:41:27 INFO - 'SHELL': '/bin/bash', 10:41:27 INFO - 'SHLVL': '1', 10:41:27 INFO - 'TERM': 'linux', 10:41:27 INFO - 'TMOUT': '86400', 10:41:27 INFO - 'USER': 'cltbld', 10:41:27 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', 10:41:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:27 INFO - '_': '/tools/buildbot/bin/python'} 10:41:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:27 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)) 10:41:27 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)) 10:41:27 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)) 10:41:27 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)) 10:41:27 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)) 10:41:27 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)) 10:41:27 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)) 10:41:27 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)) 10:41:27 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)) 10:41:27 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)) 10:41:27 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)) 10:41:27 INFO - Cleaning up... 10:41:27 INFO - Return code: 0 10:41:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:41:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19661f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c04310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', '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 10:41:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:41:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:41:27 INFO - Using env: {'DISPLAY': ':0', 10:41:27 INFO - 'HOME': '/home/cltbld', 10:41:27 INFO - 'LANG': 'en_US.UTF-8', 10:41:27 INFO - 'LANGUAGE': 'en_US:en', 10:41:27 INFO - 'LOGNAME': 'cltbld', 10:41:27 INFO - 'MAIL': '/var/mail/cltbld', 10:41:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:27 INFO - 'MOZ_NO_REMOTE': '1', 10:41:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:27 INFO - 'NO_EM_RESTART': '1', 10:41:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:27 INFO - 'PWD': '/builds/slave/test', 10:41:27 INFO - 'SHELL': '/bin/bash', 10:41:27 INFO - 'SHLVL': '1', 10:41:27 INFO - 'TERM': 'linux', 10:41:27 INFO - 'TMOUT': '86400', 10:41:27 INFO - 'USER': 'cltbld', 10:41:27 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', 10:41:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:27 INFO - '_': '/tools/buildbot/bin/python'} 10:41:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:41:27 INFO - Cleaning up... 10:41:27 INFO - Return code: 0 10:41:27 INFO - Running post-action listener: _resource_record_post_action 10:41:27 INFO - Running post-action listener: _start_resource_monitoring 10:41:27 INFO - Starting resource monitoring. 10:41:27 INFO - [mozharness: 2017-05-15 17:41:27.574276Z] Finished create-virtualenv step (success) 10:41:27 INFO - [mozharness: 2017-05-15 17:41:27.575223Z] Running install step. 10:41:27 INFO - Running pre-action listener: _resource_record_pre_action 10:41:27 INFO - Running main action method: install 10:41:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:41:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:41:27 INFO - Reading from file tmpfile_stdout 10:41:27 INFO - Detecting whether we're running mozinstall >=1.0... 10:41:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:41:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:41:27 INFO - Reading from file tmpfile_stdout 10:41:27 INFO - Output received: 10:41:27 INFO - Usage: mozinstall [options] installer 10:41:27 INFO - Options: 10:41:27 INFO - -h, --help show this help message and exit 10:41:27 INFO - -d DEST, --destination=DEST 10:41:27 INFO - Directory to install application into. [default: 10:41:27 INFO - "/builds/slave/test"] 10:41:27 INFO - --app=APP Application being installed. [default: firefox] 10:41:27 INFO - mkdir: /builds/slave/test/build/application 10:41:27 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'] 10:41:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 10:41:42 INFO - Reading from file tmpfile_stdout 10:41:42 INFO - Output received: 10:41:42 INFO - /builds/slave/test/build/application/firefox/firefox 10:41:42 INFO - Running post-action listener: _resource_record_post_action 10:41:42 INFO - [mozharness: 2017-05-15 17:41:42.008620Z] Finished install step (success) 10:41:42 INFO - [mozharness: 2017-05-15 17:41:42.008861Z] Running run-tests step. 10:41:42 INFO - Running pre-action listener: _resource_record_pre_action 10:41:42 INFO - Running main action method: run_tests 10:41:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:41:42 INFO - Python 2.7.3 10:41:42 INFO - Return code: 0 10:41:42 INFO - grabbing minidump binary from tooltool 10:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:41:42 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 0x1c01400>, '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 0x1c023b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c02840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:41:42 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 10:41:42 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 10:41:42 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 10:41:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:41:42 INFO - Return code: 0 10:41:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:41:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:41:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:41:42 INFO - ENV: RUST_BACKTRACE is now 1 10:41:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:41:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:41:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:41:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-019 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:41:42 INFO - Using env: {'DISPLAY': ':0', 10:41:42 INFO - 'HOME': '/home/cltbld', 10:41:42 INFO - 'LANG': 'en_US.UTF-8', 10:41:42 INFO - 'LANGUAGE': 'en_US:en', 10:41:42 INFO - 'LOGNAME': 'cltbld', 10:41:42 INFO - 'MAIL': '/var/mail/cltbld', 10:41:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:41:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:42 INFO - 'MOZ_NO_REMOTE': '1', 10:41:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:41:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:41:42 INFO - 'NO_EM_RESTART': '1', 10:41:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:42 INFO - 'PWD': '/builds/slave/test', 10:41:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:41:42 INFO - 'RUST_BACKTRACE': '1', 10:41:42 INFO - 'SHELL': '/bin/bash', 10:41:42 INFO - 'SHLVL': '1', 10:41:42 INFO - 'TERM': 'linux', 10:41:42 INFO - 'TMOUT': '86400', 10:41:42 INFO - 'USER': 'cltbld', 10:41:42 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536', 10:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:42 INFO - '_': '/tools/buildbot/bin/python'} 10:41:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:41:42 INFO - mozversion application_buildid: 20170515165406 10:41:42 INFO - mozversion application_changeset: 241a875b457fd4164cfe96f57865dd0969f20909 10:41:42 INFO - mozversion application_display_name: Firefox 10:41:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:41:42 INFO - mozversion application_name: Firefox 10:41:42 INFO - mozversion application_remotingname: firefox 10:41:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:41:42 INFO - mozversion application_vendor: Mozilla 10:41:42 INFO - mozversion application_version: 54.0 10:41:42 INFO - mozversion platform_buildid: 20170515165406 10:41:42 INFO - mozversion platform_changeset: 241a875b457fd4164cfe96f57865dd0969f20909 10:41:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:41:42 INFO - mozversion platform_version: 54.0 10:41:42 INFO - using testdate: 1494870102 10:41:42 INFO - actual date: 1494870102 10:41:42 INFO - starting webserver on 'localhost:36541' 10:41:42 INFO - SUITE-START | Running 2 tests 10:41:42 INFO - TEST-START | dromaeo_css 10:41:42 INFO - operating with platform_type : linux_ 10:41:42 INFO - Initialising browser for dromaeo_css test... 10:41:42 INFO - TEST-INFO | started process 3334 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_DAh5x/profile http://localhost:36541/getInfo.html) 10:41:46 INFO - PROCESS | 3334 | __metrics Screen width/height:1600/1200 10:41:46 INFO - PROCESS | 3334 | colorDepth:24 10:41:46 INFO - PROCESS | 3334 | Browser inner width/height: 1024/697 10:41:46 INFO - PROCESS | 3334 | __metrics 10:41:47 INFO - PROCESS | 3334 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:41:47 INFO - PROCESS | 3334 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:41:48 INFO - TEST-INFO | 3334: exit 0 10:41:48 INFO - Browser initialized. 10:41:48 INFO - Running cycle 1/1 for dromaeo_css test... 10:41:48 INFO - TEST-INFO | started process 3425 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_DAh5x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:42:00 INFO - PROCESS | 3425 | RSS: Main: 141393920 10:42:00 INFO - PROCESS | 3425 | 10:45:01 INFO - PROCESS | 3425 | Cycle 1(1): loaded http://localhost:36541/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36541/tests/dromaeo/cssquery-ext.html) 10:45:03 INFO - PROCESS | 3425 | RSS: Main: 265936896 10:45:03 INFO - PROCESS | 3425 | 10:48:05 INFO - PROCESS | 3425 | Cycle 1(1): loaded http://localhost:36541/tests/dromaeo/cssquery-ext.html (next: http://localhost:36541/tests/dromaeo/cssquery-jquery.html) 10:48:05 INFO - PROCESS | 3425 | RSS: Main: 228003840 10:48:05 INFO - PROCESS | 3425 | 10:51:07 INFO - PROCESS | 3425 | Cycle 1(1): loaded http://localhost:36541/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36541/tests/dromaeo/cssquery-mootools.html) 10:51:08 INFO - PROCESS | 3425 | RSS: Main: 203317248 10:51:08 INFO - PROCESS | 3425 | 10:54:10 INFO - PROCESS | 3425 | Cycle 1(1): loaded http://localhost:36541/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36541/tests/dromaeo/cssquery-prototype.html) 10:54:10 INFO - PROCESS | 3425 | RSS: Main: 172040192 10:54:10 INFO - PROCESS | 3425 | 10:57:13 INFO - PROCESS | 3425 | Cycle 1(1): loaded http://localhost:36541/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36541/tests/dromaeo/cssquery-yui.html) 10:57:13 INFO - PROCESS | 3425 | RSS: Main: 178446336 10:57:13 INFO - PROCESS | 3425 | 11:00:16 INFO - PROCESS | 3425 | Cycle 1(1): loaded http://localhost:36541/tests/dromaeo/cssquery-yui.html (next: http://localhost:36541/tests/dromaeo/cssquery-dojo.html) 11:00:16 INFO - PROCESS | 3425 | RSS: Main: 261496832 11:00:16 INFO - PROCESS | 3425 | 11:00:16 INFO - PROCESS | 3425 | __start_tp_report 11:00:16 INFO - PROCESS | 3425 | _x_x_mozilla_page_load 11:00:16 INFO - PROCESS | 3425 | _x_x_mozilla_page_load_details 11:00:16 INFO - PROCESS | 3425 | |i|pagename|runs| 11:00:16 INFO - PROCESS | 3425 | |0;dojo.html;3331.050650564589;3375.206826395798;3405.3189362127573;3440.6559344065595;3481.5125882376474;5716.428357164282;5747.626404283718;5777.711114444277;5784.1902133701315;5786.305643322808;5783.334916484606;5832.912506312408;5836.270466191726;5839.532837373011;5845.473907348338;5157.664751791141;5173.249878767577;5187.922181167285;5202.895942081156;5240.161574148137;5028.547430731233;5064.290999260106;5255.316808814849;5256.9474305256845;5263.999840030382;5127.461616530267;5133.358330208724;5160.948390516084;5167.715775632339;5171;5237.109691352479;5242.135047717122;5245.370555533323;5247.317848679685;5274.235235890813;4997.925031124536;5108.208227724708;5168;5209.114450543379;5221.843344699665;5655;5738.971305143489;5839.824805255849;5841.85395365115;5844.47399734026;6183.319834818167;6185.103160041769;6215.471684906788;6229.968850155766;6230.190075290228;5996.370381110008;6259.593126446766;6260.874782504325;6284.27730810958;6288.276848162474;5703.31560212773;5715.0855863062125;5744.569157313218;5805.506531944748;5813.418658134237;6286.528510361742;6307.463865571386;6313.842153946188;6315.810525684236;6319.146915166394;6015.187949626832;6064.272287325461;6066.332703402622;6069.2716873975405;6075.119107729354;6000.669963151981;6009.939900600938;6113.2330707408255;6115.453310713017;6130.325664176937;6136.140940268366;6161.137440758297;6166;6182.443580077815;6186.536009799283;6018.608790428608;6033.788817391458;6035.275766907999;6035.486983606354;6193.287771906288;4617.607503362185;4662;4664.836730714409;4671.27595222741;4688.437387513521;4230.111676547959;4239.59723826236;4244.490661120685;4247.787610619456;4250.171216612731;5816.709164541756;5851.912221316684;5876.912771137268;5907.675077870673;5954.047352423591;6225.750969961151;6228.5951413158;6258.43674069336;6258.624482531062;6259.874802503924;5949.732262048218;5958.880822383528;5970.34326224115;5973.163757074013;5987;5121.129408000649;5146.511081447256;5239.659422137625;5272.525472707474;5279.498447647467;6516.609003459807;6517.608943463408;6518.57629254097;6524.380183882524;6538.378854008862;6170.53720970938;6262.655553944577;6263.812085637256;6269.874602508015;6274.6548939808745;6276.686165691606;6288.748450062129;6540.803775886731;6550.934490655032;6557.803265902031;6111.96944015277;6119.3268740437625;6137.438273608381;6145.678679084019;6155.446009858955;6260.906086408617;6268.905966410416;6275.52933529978;6277.5605707600025;6279.152314437675;6052.122442245938;6057.515398768;6099.725512352044;6111.96944015277;6122.816315510542;5928.970355148197;5959.553033522417;6001.987346891948;6061.242344706911;6070.878582428415;6065.484433823174;6069.2716873975405;6072;6081.331053584014;6110.847228819315;5984.820455386345;6043.063325184604;6112.847178820565;6114.2357205349335;6116.8776624468155;5812.389699081536;5918.733656985532;5969.761209551743;5971.970140149271;5974.9103763444455 11:00:16 INFO - PROCESS | 3425 | |1;ext.html;762.4167511853433;813.4590497319283;835.7409203147025;902.210565754964;916.7478943290598;31018;43505.78247108764;43970.34044489327;44045.33931991023;44064;42337.78831105848;42480.150396992045;42611.14777704444;46732;46898.76550617251;7603;8918.955405222981;9037.59330830111;9043.14090161437;9044.321675874302;7459.664315105833;10561.788764224711;10716.732081697943;10723.624673136443;10727.517261723242;10835.945820270927;10851.186161037915;10866.67399977998;10877.782444351109;10882.61910833121;10762.139028877671;10776.084032857216;10807.243492955497;10813.945930270338;10828.079613232885;10522.631707890263;10542.10392116671;10562.735931601772;10565.788684226272;10567.313124646951;41542.169156617;41705.79147104245;41806.581934180576;41812;41825.79087104545;4286.828526858922;4350.630196433307;4351.456067991501;4476.037651904823;4496.9325460118125;29555.852220738972;29666.516674166207;29716.257093572618;29775.70224297751;31363.529547056813;4225.260579398624;4335.284678028122;4338.457692788402;4409.11817636474;4438.977805110953;240590;242144.99999999822;242377;242638;242697;46291.76854115708;46401.30398044032;46436.30345544819;46467;46498.5350146501;48077;48088.5191148091;48121;48149.03701925942;48191;15679.216039197994;15958.1223032732;15962;15967.520974370787;15968.441104561516;12208.572699955637;12325.260484370967;13866.930665346608;13958.651033724238;13971.58085257444;12456.0035197184;12528.123031387715;12738.936305318413;12921.676958076123;13008.804867926989;5429.918551221735;5577.944220557823;5586.301712285965;5610.85972850682;5640.5769567281795;1015.0914931136739;1017.5319353097658;1018.5518371916334;1022.5500779656927;1025.36939782035;29347.266318341786;29395.55906661402;29685.554716679268;29990.55014174789;30205.395892082037;1129.6441620889557;1184.6209213051914;1194.3490797515376;1200.351810022578;1239.9690007749698;13231.27228002469;13591.357798437833;13596.116252443757;13814.516491922734;13960.650983725489;363838;364951;367335;368039;368166;48129.75935120372;48683;48695.756521217874;48723.26915096276;48729.756351218726;1435.798988141661;1468.4199741102204;1478.911265324084;1495.2673190136973;1508.351408894186;27558.448831023667;28262.43475130445;28556.714432856235;29346.55980160342;29466.558001629564;24330.026798927844;24445.26664200077;24481.51036979286;24498.265052048468;24516.87741561281;321280;322013;322983;323372;324732;25770.96916123334;26126.608100878122;30644.387112258075;31112.066638000895;31562.68437315689;36848.81575592105;37002;37190.44214336841;37547.43678844765;37984.81007594944;30248.243793905327;30634.693653064074;31031.224219394695;31351.84324078365;31499.842500788265;30101.69898300989;30179.396412072074;30453;30486.237844054078;31051.378972420876;33831.4925276126;34374.828125860215;34375;34812.477812833335;34853.12867178341;29032.854835725688;29073;29308.41383172282;29324.266893327836;29665.25836854096;23272.53454930858;24078.51842963166;24268.75731242665;24653.260402187963;24716 11:00:16 INFO - PROCESS | 3425 | |2;jquery.html;460.9331646911197;469.8543451530026;476.50919552860523;480.2795806290564;487.20585445723464;20125.698114528277;21243.893780531114;21299.04154313056;21648.89175554122;21782.019809108588;22045.669314960287;22085.116595336167;22328.553428931413;22439.77560224401;22613;2072.5025993761537;2073.0153177240763;2349.4713689419914;2814.9718502815017;6686.966565167192;6578.572392794453;6698.93301066988;6776.491763117761;6776.966115169417;6788.11754471918;6686.83282917926;6750.62871542065;6797.864042719143;6799.966000169992;6812.557183783063;6606.273309935903;6640.501962352819;6702.564333318319;6705.631190284533;6710.899336509956;6493.707783149723;6520.739170433177;6534.869302613969;6537.444317233041;6540.3132671069325;24395.268141955592;24550;24772.752272477224;24954;25218.495630087295;3155.384699983504;3237.5629290045777;3249.5938007749032;3327.617324007722;3332.3002169544425;15599.844001559952;16273;16470;16663.583410414838;16905.49283521484;2330.4290448839974;2419.8911049002663;2478.7397323280975;2484.63972723954;2515.5472015037285;96054;97080.51459742726;98416.50791746067;99074.5046274764;99270;17841.7323740144;17890.821091789177;18330.541736456427;18416.81583184151;18633.440996770118;32873.84941527035;32971.340573188405;32988;33019.504707429405;33046.3390732184;11119.885642312234;11207.943960280147;11283.830742538868;11313.094952403788;11445.771084578262;9547.952260238655;9582.035682685033;9584.952075239578;9751.707448776548;9824.607015719292;9548;10155.39067655943;10230.386176829414;10284.023017813295;10398.532066057045;3734.3464893643504;3848.191879705237;3892.7576601670858;3904.882853514399;3909.3354129798004;981.8085473332775;995.9004099589984;1001.4592120254981;1010.6159659329555;1020.7193021919031;20707.585848283252;21115.155393784076;21143.471413214793;21247.78752212489;21382.786172138392;1020.5917632946881;1039.7244730146517;1045.9738506537396;1046.842973553973;1054.467493915583;11243.55025798959;11940.641780746591;12104.81842772359;12199.817002744965;12269.141160118885;650256;656707;661376;664896;665448;87380;87640;89113.55443222742;90042;90549.54725226463;1302.433441452971;1316.8814806667447;1325.2181213084327;1332.5202926946292;1341.1483707845393;17613.29546818164;17870.91064544669;18190.181441835415;18408.71607776479;18527.444176674722;41106.32808867414;41313.58686413097;41378.586214138675;41657.37513937354;41682;50376.24435633542;51161.58209029897;52043.73978130085;52069.47930520798;52949.470505294456;12442.253464792142;12562.87437125617;12566.434510447234;12637.2417654941;12670.873291267339;13400.597982060553;13462.461501539829;13689.110207836458;13754.93122534421;14202.644933876736;11872.82190767122;12052.397380131133;12172.208806427554;12214.26714397139;12347.506099755909;11006.944965275123;11198.328100313682;11339.773204536028;11453.541858325907;11756.82364764512;12024;12408.131430799758;12424.316662583644;12633.87366126327;12767.361631918553;10822.170992487929;12562;12593.496260149492;12618.369081545703;12739.426725797175;12216;12339.321337326533;12408.193467424588;12425.502979880703;12451.439685214005 11:00:16 INFO - PROCESS | 3425 | |3;mootools.html;1432.9283535823206;1587.7221486239905;1597.8801589880754;1608.6219738361485;1625.739881618941;9389.201917836983;11247.83128253077;11571.13216508762;11831.230969986966;11864.7627047459;11660.766784664302;11673.241239319439;11726.765464690701;11753.941230293836;11758.353290569015;2089.9059542320633;2171.8045375916163;2404.8316617836713;2439.231642032763;2439.524292762912;2474.257722683197;2556.6676332076804;2556.7187609362954;2564.3204550794053;2565.217608629367;2492.2149522900318;2503.5368456835454;2509.7992160627105;2520.7227205007434;2559.795216382685;2468.136152346682;2494.750524947509;2500.1499490173337;2500.199936020474;2500.6499090127395;2369.9170529031403;2372.41875740443;2391.916282930089;2397.700287464067;2399.6280576510667;11312.151588630803;11657;11734.295942243407;11809.114316426303;11809.881901180965;5676.858078548069;5849.532037436995;5850.590458667895;5860.7948721794955;5861.765529378862;3441.8795342162903;3445.3626079175433;3512.7892326460496;3611.5485564304463;3678.172411207457;1292.3344477594046;1319.1096010193178;1340.2561578324026;1392.3803907261272;1393.881520070785;17025.659486810317;17154.914225428918;17171.742423863652;17183.312667493286;17207.827921720873;4835.8065677373215;4937.728424936591;4988.501149884983;5044.066847633199;5057.443681195065;13249;13249.337533123306;13249.801252981213;13252.469901203844;13277.40251688657;6087.269527656709;6097.786577469856;6104.786332478342;6106.664133472702;6107.572469927062;4569.908601827945;5232.659877107976;5541.445855414507;5687.7156142192725;5696;4128.453836290842;4169.687273454503;4191.476065491814;4214.473190851144;4232.915341693149;1039.2257767962765;1042.3329069395593;1057.7620035491952;1059.6397224943555;1073.103958194916;1072.340510585984;1094.261373572835;1095.7589330347312;1098.3794156301665;1108.3128460354633;7564.432667549956;7568.167501574932;7618.352440042659;7621.3902887768845;7759.650815713307;1061.6390427254616;1098.7253186703324;1100.3837850803575;1115.0800589513683;1126.1553834624033;4458.331250312479;4574.382458368144;4579.725216486955;4591.471980722193;4737.549932756383;14528;15167.24163791806;15175.392984280505;15198.164100974553;15206.315715792816;14806.925965370105;14895.77656335156;14977.176255306063;15031.774523382159;15040.323185456462;10558;10866;10874.945625271981;11047.613333533449;11061.889381106086;6872.999785312259;7073.363397294268;7111.684338397413;7244.818879528054;7254.600996945219;12831;13509.459621615419;13535.526256580972;13695.794563081761;13751.931240344134;13686.384112715152;13839.446422143;13843.79234311466;14027;14033.578992630237;8585.227329540372;8802.735917922473;9271.721848344561;9493.620255189715;9826.16477599412;10786.838197426889;10859.565617375532;10950.945245273722;10961.780764384508;11029.669109926715;9209.447433154031;9346.392484488486;9369.390989585656;9473.952630236805;9508.952455237679;9126.224270937044;9149.542522873962;9228.35401521887;9255.25957923351;9311.115444032806;8531.104234055334;8654.567271636519;8664.393492455467;8692.261157801659;8715.694950676696;6678.966605166943;6693.598384096969;6703.731850725916;6776.966115169393;6828.624425656637;6505.316941721051;6643.833904152435;6668.499862510428;6755.560888542229;6755.7973260802255 11:00:16 INFO - PROCESS | 3425 | |4;prototype.html;426.6181767318252;449.4741152851164;451.4582500998802;453.25892166308086;456.0559641542009;5258.973705131474;5281.234221037952;5285.365756109262;5298.1522956327;5364.183803503162;5129.384473863132;5161.432242453337;5239.214117882319;5269.235960785683;5303.284056652351;4182.97908510457;4190.748555086697;4194.65770953295;4196.223698615766;4218.797642671839;4184.602462766047;4193.643540299078;4197.874063778077;4209.915801683965;4217.15656868626;4148.865235966708;4215.283401821697;4222.416593777333;4229.577042295777;4254.340577210522;4018.7943616914954;4138.448081969246;4173.643565841114;4176.4570605821355;4188.717149153718;3844.942325865114;3849.615038496128;3850.6137790395437;3854.383298672199;3873.86441474547;5109.518239710479;5139.897202055975;5139.997700448376;5142.922856157161;5142.97428512855;4843.66094373394;4875.293082503017;5066.6186892160995;5121.667091639031;5161.967606478682;5444.3122631983815;5513.972430137864;5529.668219906818;5537.695426751527;5541.501264886179;4586.472555656075;4588.426446694163;4589.495155532889;4600.608948239404;4609.792559334838;5482.671039737628;5654.465958775806;5684.658920464744;5690.40250773667;5750.05124154514;4696.788644510937;4708.788104535304;4721.409823772028;4725.8025018366625;4741.905161896742;4783.904321913542;4783.976080119577;4795.280707893774;4804.855854324376;4813.470518243061;4844.632980078812;5078.187490001582;5079.187330027178;5082.364704411949;5091.9236211456855;5456.208849716769;5461.290032295776;5476.424975377528;5480.671159730429;5486.6982315973;1556.750919852818;1654.4788391656589;1663.0853030832993;1669.1570756767996;1671.9498415047567;1245.9314737689515;1270.9364531773374;1272.7390884868764;1295.9870401296055;1298.571471414431;5295.232191332235;5361.785528578891;5385.3268341457315;5400.378956419983;5422.783088676488;5593.860153496195;5600.439956004367;5616.213730077792;5630.268065151504;5640.351359593699;5252.868678283074;5294.735263236823;5315.468453154731;5378.462153784669;5408.215288954636;2930.575066615329;2933.7212964768314;2943.440746258204;2946.219251898249;2953.4388466191785;5793.594448388657;5819.82540523785;5834.299884013945;5841.620294680833;5861.5346163459135;5492.395836457987;5531.502164805187;5639.859003524863;5717.656940583578;5737.3688894221605;5719.628224165416;5759.798407055733;5765.509931655856;5776.826695199151;5940.910886336622;5572.526335261386;5607.719614019365;5632.605717599728;5703.429657034263;5712.714364281853;5306.867328316823;5481.917771233355;5482.643628164156;5546.4453554644215;5582.246396736388;5844.918690042356;5852.858692554911;5852.97572924745;5855.238818953509;5863.501602363676;4509.9774501128595;4578.175928332932;4682.906341873211;4716.457607375196;4719.197736384892;5517.834464966058;5575.275214222125;5575.470330318613;5595.132754423071;5670;5463.48096930791;5511.751971161227;5514.310711161105;5526.944730552804;5535.612507124462;5222.556082732858;5427.430119837518;5479.506844384025;5492.709213334783;5500.614956952975;5291.809342897817;5316.548093412103;5343.572514198777;5442.755076021668;5460.726963651881;5324.0150572144275;5366.436524164907;5496.883699775695;5501.669899805865;5524.502794748331;5163.483651634807;5335.2263921730355;5367.1892956001675;5372.409035006068;5391.4338994405025 11:00:16 INFO - PROCESS | 3425 | |5;yui.html;853.4495250563383;857.6526506764762;913.5021413329737;920.638216869138;921.7833809054871;17904.91047544762;18364.35724749634;18422.447326580223;18668.159932803024;18868.339608113718;17375.13124343781;17761.555961100978;17794.82205177948;17910.820891791078;17924.193411296492;883.3021912689001;892.3664198419131;892.5581836990699;892.5581836990699;894.021046953588;892.9866052009226;902.8194361127768;903.5617725403174;903.8282726281985;905.3617199874072;956.3162338927665;958.5542722633974;959.3620242538739;959.5106495687215;961.5480724059681;951.2057432044217;954.2127744610689;955.8375076237056;955.9665411710557;960.2893858544627;692.8198668346216;698.6122701900443;699.1400577289962;699.2064007351642;699.5837476701355;19099.809001910082;19199.80800191994;19328;19424.902875485674;19681.606367872562;17372.56568585783;17421.128943552772;17620.647587048188;17706.380276690383;18282.08589570516;12439.875601243963;12756.425960831784;12774.744505109938;12812.10315277931;12898.80651790224;4203.26442872496;4220.556841531657;4258.6380157686635;4301.5698430156735;4358.128374325116;49830.13559948988;53578.46421535774;66581.66709166471;83339.7499037521;90809.54595227048;14084;14684.339204735812;14738.705225895423;14749.262536873113;14801;26368.73631263701;26376.736232637428;26399.604005939927;26413;26974;9693.854592181124;9766.121049105615;9773.951130244302;9785.804283914282;9815.36200146988;5168.121419358718;5217.217417387422;5229.346331708536;5237.476252374809;5276.129438642658;3932.036651020518;4039.0710136668145;4039.4546736190828;4042.2521833460905;4044.2315959967514;684.852756657321;690.0856365315976;703.6024646074951;703.968321425537;706.9363757261871;818.3248819723751;841.2176675691543;843.1399972028497;850.4047166983137;862.1292494580393;11851.585194518151;12067.75864482723;12080.879191207976;12174.016414960612;12280.815787763191;758.3705524414722;765.9195784442664;785.2500861677199;790.707438247852;795.7254747112237;5318.7074710891275;5527.198556209327;5654.349749778746;5667.631603945731;5696.11710184922;83320.16679833246;84730.15269847345;85657.57171214104;85772.14227857642;87030.56484717662;46110;59480.40519594749;70968.64515677388;73512;75194.24805751981;64646.35353646497;66009.33990660128;66228;66429.6678516614;66785.33214667888;12016.038716902453;12236.898679118922;12292.569760058355;12437.130306301731;12517.687057823627;44304.33543496914;44589.33116003198;45088.0982380344;45226.54773452223;45666.543334567556;52948.735256323474;53000;53436.19845702395;54080.18879716886;56143.71928140333;13579.253141077335;13894.722105558034;13896.51362202318;13932.721345573234;14021.158730476203;16565.58586035359;16816.075115868745;16854.831451685328;17043.31826726917;17124;16051.678966420373;16095.436659716854;16121.193940303172;16171;16308.266128024505;13808.1024733392;14220.94846266759;14298.642533936734;14303.141811491343;14825.555233343017;14383.784243236569;14540.781888271473;14753.852461475677;15006;15189.924050379677;10859.34843909368;11004.559817607205;11131.443427828739;11866.703332416759;11939.104567157672;11716.765664686829;11848.407579621156;12109.394530273627;12128.93935530352;12245.571405000783 11:00:16 INFO - PROCESS | 3425 | __end_tp_report 11:00:16 INFO - PROCESS | 3425 | __start_cc_report 11:00:16 INFO - PROCESS | 3425 | _x_x_mozilla_cycle_collect,1233 11:00:16 INFO - PROCESS | 3425 | __end_cc_report 11:00:16 INFO - PROCESS | 3425 | __startTimestamp1494871216563__endTimestamp 11:00:16 INFO - PROCESS | 3425 | 11:00:16 INFO - PROCESS | 3425 | ------- Summary: start ------- 11:00:16 INFO - PROCESS | 3425 | Number of tests: 6 11:00:16 INFO - PROCESS | 3425 | 11:00:16 INFO - PROCESS | 3425 | [#0] dojo.html Cycles:165 Average:5743.97 Median:5987.00 stddev:668.39 (11.2%) stddev-sans-first:643.08 11:00:16 INFO - PROCESS | 3425 | Values: 3331.1 3375.2 3405.3 3440.7 3481.5 5716.4 5747.6 5777.7 5784.2 5786.3 5783.3 5832.9 5836.3 5839.5 5845.5 5157.7 5173.2 5187.9 5202.9 5240.2 5028.5 5064.3 5255.3 5256.9 5264.0 5127.5 5133.4 5160.9 5167.7 5171.0 5237.1 5242.1 5245.4 5247.3 5274.2 4997.9 5108.2 5168.0 5209.1 5221.8 5655.0 5739.0 5839.8 5841.9 5844.5 6183.3 6185.1 6215.5 6230.0 6230.2 5996.4 6259.6 6260.9 6284.3 6288.3 5703.3 5715.1 5744.6 5805.5 5813.4 6286.5 6307.5 6313.8 6315.8 6319.1 6015.2 6064.3 6066.3 6069.3 6075.1 6000.7 6009.9 6113.2 6115.5 6130.3 6136.1 6161.1 6166.0 6182.4 6186.5 6018.6 6033.8 6035.3 6035.5 6193.3 4617.6 4662.0 4664.8 4671.3 4688.4 4230.1 4239.6 4244.5 4247.8 4250.2 5816.7 5851.9 5876.9 5907.7 5954.0 6225.8 6228.6 6258.4 6258.6 6259.9 5949.7 5958.9 5970.3 5973.2 5987.0 5121.1 5146.5 5239.7 5272.5 5279.5 6516.6 6517.6 6518.6 6524.4 6538.4 6170.5 6262.7 6263.8 6269.9 6274.7 6276.7 6288.7 6540.8 6550.9 6557.8 6112.0 6119.3 6137.4 6145.7 6155.4 6260.9 6268.9 6275.5 6277.6 6279.2 6052.1 6057.5 6099.7 6112.0 6122.8 5929.0 5959.6 6002.0 6061.2 6070.9 6065.5 6069.3 6072.0 6081.3 6110.8 5984.8 6043.1 6112.8 6114.2 6116.9 5812.4 5918.7 5969.8 5972.0 5974.9 11:00:16 INFO - PROCESS | 3425 | 11:00:16 INFO - PROCESS | 3425 | [#1] ext.html Cycles:180 Average:45992.55 Median:30192.40 stddev:82660.74 (273.8%) stddev-sans-first:82822.86 11:00:16 INFO - PROCESS | 3425 | Values: 762.4 813.5 835.7 902.2 916.7 31018.0 43505.8 43970.3 44045.3 44064.0 42337.8 42480.2 42611.1 46732.0 46898.8 7603.0 8919.0 9037.6 9043.1 9044.3 7459.7 10561.8 10716.7 10723.6 10727.5 10835.9 10851.2 10866.7 10877.8 10882.6 10762.1 10776.1 10807.2 10813.9 10828.1 10522.6 10542.1 10562.7 10565.8 10567.3 41542.2 41705.8 41806.6 41812.0 41825.8 4286.8 4350.6 4351.5 4476.0 4496.9 29555.9 29666.5 29716.3 29775.7 31363.5 4225.3 4335.3 4338.5 4409.1 4439.0 240590.0 242145.0 242377.0 242638.0 242697.0 46291.8 46401.3 46436.3 46467.0 46498.5 48077.0 48088.5 48121.0 48149.0 48191.0 15679.2 15958.1 15962.0 15967.5 15968.4 12208.6 12325.3 13866.9 13958.7 13971.6 12456.0 12528.1 12738.9 12921.7 13008.8 5429.9 5577.9 5586.3 5610.9 5640.6 1015.1 1017.5 1018.6 1022.6 1025.4 29347.3 29395.6 29685.6 29990.6 30205.4 1129.6 1184.6 1194.3 1200.4 1240.0 13231.3 13591.4 13596.1 13814.5 13960.7 363838.0 364951.0 367335.0 368039.0 368166.0 48129.8 48683.0 48695.8 48723.3 48729.8 1435.8 1468.4 1478.9 1495.3 1508.4 27558.4 28262.4 28556.7 29346.6 29466.6 24330.0 24445.3 24481.5 24498.3 24516.9 321280.0 322013.0 322983.0 323372.0 324732.0 25771.0 26126.6 30644.4 31112.1 31562.7 36848.8 37002.0 37190.4 37547.4 37984.8 30248.2 30634.7 31031.2 31351.8 31499.8 30101.7 30179.4 30453.0 30486.2 31051.4 33831.5 34374.8 34375.0 34812.5 34853.1 29032.9 29073.0 29308.4 29324.3 29665.3 23272.5 24078.5 24268.8 24653.3 24716.0 11:00:16 INFO - PROCESS | 3425 | 11:00:16 INFO - PROCESS | 3425 | [#2] jquery.html Cycles:180 Average:35780.21 Median:22206.84 stddev:107913.44 (485.9%) stddev-sans-first:108183.58 11:00:16 INFO - PROCESS | 3425 | Values: 460.9 469.9 476.5 480.3 487.2 20125.7 21243.9 21299.0 21648.9 21782.0 22045.7 22085.1 22328.6 22439.8 22613.0 2072.5 2073.0 2349.5 2815.0 6687.0 6578.6 6698.9 6776.5 6777.0 6788.1 6686.8 6750.6 6797.9 6800.0 6812.6 6606.3 6640.5 6702.6 6705.6 6710.9 6493.7 6520.7 6534.9 6537.4 6540.3 24395.3 24550.0 24772.8 24954.0 25218.5 3155.4 3237.6 3249.6 3327.6 3332.3 15599.8 16273.0 16470.0 16663.6 16905.5 2330.4 2419.9 2478.7 2484.6 2515.5 96054.0 97080.5 98416.5 99074.5 99270.0 17841.7 17890.8 18330.5 18416.8 18633.4 32873.8 32971.3 32988.0 33019.5 33046.3 11119.9 11207.9 11283.8 11313.1 11445.8 9548.0 9582.0 9585.0 9751.7 9824.6 9548.0 10155.4 10230.4 10284.0 10398.5 3734.3 3848.2 3892.8 3904.9 3909.3 981.8 995.9 1001.5 1010.6 1020.7 20707.6 21115.2 21143.5 21247.8 21382.8 1020.6 1039.7 1046.0 1046.8 1054.5 11243.6 11940.6 12104.8 12199.8 12269.1 650256.0 656707.0 661376.0 664896.0 665448.0 87380.0 87640.0 89113.6 90042.0 90549.5 1302.4 1316.9 1325.2 1332.5 1341.1 17613.3 17870.9 18190.2 18408.7 18527.4 41106.3 41313.6 41378.6 41657.4 41682.0 50376.2 51161.6 52043.7 52069.5 52949.5 12442.3 12562.9 12566.4 12637.2 12670.9 13400.6 13462.5 13689.1 13754.9 14202.6 11872.8 12052.4 12172.2 12214.3 12347.5 11006.9 11198.3 11339.8 11453.5 11756.8 12024.0 12408.1 12424.3 12633.9 12767.4 10822.2 12562.0 12593.5 12618.4 12739.4 12216.0 12339.3 12408.2 12425.5 12451.4 11:00:16 INFO - PROCESS | 3425 | 11:00:16 INFO - PROCESS | 3425 | [#3] mootools.html Cycles:180 Average:7268.65 Median:2483.24 stddev:4649.29 (187.2%) stddev-sans-first:4641.65 11:00:16 INFO - PROCESS | 3425 | Values: 1432.9 1587.7 1597.9 1608.6 1625.7 9389.2 11247.8 11571.1 11831.2 11864.8 11660.8 11673.2 11726.8 11753.9 11758.4 2089.9 2171.8 2404.8 2439.2 2439.5 2474.3 2556.7 2556.7 2564.3 2565.2 2492.2 2503.5 2509.8 2520.7 2559.8 2468.1 2494.8 2500.1 2500.2 2500.6 2369.9 2372.4 2391.9 2397.7 2399.6 11312.2 11657.0 11734.3 11809.1 11809.9 5676.9 5849.5 5850.6 5860.8 5861.8 3441.9 3445.4 3512.8 3611.5 3678.2 1292.3 1319.1 1340.3 1392.4 1393.9 17025.7 17154.9 17171.7 17183.3 17207.8 4835.8 4937.7 4988.5 5044.1 5057.4 13249.0 13249.3 13249.8 13252.5 13277.4 6087.3 6097.8 6104.8 6106.7 6107.6 4569.9 5232.7 5541.4 5687.7 5696.0 4128.5 4169.7 4191.5 4214.5 4232.9 1039.2 1042.3 1057.8 1059.6 1073.1 1072.3 1094.3 1095.8 1098.4 1108.3 7564.4 7568.2 7618.4 7621.4 7759.7 1061.6 1098.7 1100.4 1115.1 1126.2 4458.3 4574.4 4579.7 4591.5 4737.5 14528.0 15167.2 15175.4 15198.2 15206.3 14806.9 14895.8 14977.2 15031.8 15040.3 10558.0 10866.0 10874.9 11047.6 11061.9 6873.0 7073.4 7111.7 7244.8 7254.6 12831.0 13509.5 13535.5 13695.8 13751.9 13686.4 13839.4 13843.8 14027.0 14033.6 8585.2 8802.7 9271.7 9493.6 9826.2 10786.8 10859.6 10950.9 10961.8 11029.7 9209.4 9346.4 9369.4 9474.0 9509.0 9126.2 9149.5 9228.4 9255.3 9311.1 8531.1 8654.6 8664.4 8692.3 8715.7 6679.0 6693.6 6703.7 6777.0 6828.6 6505.3 6643.8 6668.5 6755.6 6755.8 11:00:16 INFO - PROCESS | 3425 | 11:00:16 INFO - PROCESS | 3425 | [#4] prototype.html Cycles:180 Average:4755.91 Median:5293.27 stddev:1277.71 (24.1%) stddev-sans-first:1239.28 11:00:16 INFO - PROCESS | 3425 | Values: 426.6 449.5 451.5 453.3 456.1 5259.0 5281.2 5285.4 5298.2 5364.2 5129.4 5161.4 5239.2 5269.2 5303.3 4183.0 4190.7 4194.7 4196.2 4218.8 4184.6 4193.6 4197.9 4209.9 4217.2 4148.9 4215.3 4222.4 4229.6 4254.3 4018.8 4138.4 4173.6 4176.5 4188.7 3844.9 3849.6 3850.6 3854.4 3873.9 5109.5 5139.9 5140.0 5142.9 5143.0 4843.7 4875.3 5066.6 5121.7 5162.0 5444.3 5514.0 5529.7 5537.7 5541.5 4586.5 4588.4 4589.5 4600.6 4609.8 5482.7 5654.5 5684.7 5690.4 5750.1 4696.8 4708.8 4721.4 4725.8 4741.9 4783.9 4784.0 4795.3 4804.9 4813.5 4844.6 5078.2 5079.2 5082.4 5091.9 5456.2 5461.3 5476.4 5480.7 5486.7 1556.8 1654.5 1663.1 1669.2 1671.9 1245.9 1270.9 1272.7 1296.0 1298.6 5295.2 5361.8 5385.3 5400.4 5422.8 5593.9 5600.4 5616.2 5630.3 5640.4 5252.9 5294.7 5315.5 5378.5 5408.2 2930.6 2933.7 2943.4 2946.2 2953.4 5793.6 5819.8 5834.3 5841.6 5861.5 5492.4 5531.5 5639.9 5717.7 5737.4 5719.6 5759.8 5765.5 5776.8 5940.9 5572.5 5607.7 5632.6 5703.4 5712.7 5306.9 5481.9 5482.6 5546.4 5582.2 5844.9 5852.9 5853.0 5855.2 5863.5 4510.0 4578.2 4682.9 4716.5 4719.2 5517.8 5575.3 5575.5 5595.1 5670.0 5463.5 5511.8 5514.3 5526.9 5535.6 5222.6 5427.4 5479.5 5492.7 5500.6 5291.8 5316.5 5343.6 5442.8 5460.7 5324.0 5366.4 5496.9 5501.7 5524.5 5163.5 5335.2 5367.2 5372.4 5391.4 11:00:16 INFO - PROCESS | 3425 | 11:00:16 INFO - PROCESS | 3425 | [#5] yui.html Cycles:180 Average:18649.29 Median:44446.83 stddev:22262.72 (50.1%) stddev-sans-first:22285.06 11:00:16 INFO - PROCESS | 3425 | Values: 853.4 857.7 913.5 920.6 921.8 17904.9 18364.4 18422.4 18668.2 18868.3 17375.1 17761.6 17794.8 17910.8 17924.2 883.3 892.4 892.6 892.6 894.0 893.0 902.8 903.6 903.8 905.4 956.3 958.6 959.4 959.5 961.5 951.2 954.2 955.8 956.0 960.3 692.8 698.6 699.1 699.2 699.6 19099.8 19199.8 19328.0 19424.9 19681.6 17372.6 17421.1 17620.6 17706.4 18282.1 12439.9 12756.4 12774.7 12812.1 12898.8 4203.3 4220.6 4258.6 4301.6 4358.1 49830.1 53578.5 66581.7 83339.7 90809.5 14084.0 14684.3 14738.7 14749.3 14801.0 26368.7 26376.7 26399.6 26413.0 26974.0 9693.9 9766.1 9774.0 9785.8 9815.4 5168.1 5217.2 5229.3 5237.5 5276.1 3932.0 4039.1 4039.5 4042.3 4044.2 684.9 690.1 703.6 704.0 706.9 818.3 841.2 843.1 850.4 862.1 11851.6 12067.8 12080.9 12174.0 12280.8 758.4 765.9 785.3 790.7 795.7 5318.7 5527.2 5654.3 5667.6 5696.1 83320.2 84730.2 85657.6 85772.1 87030.6 46110.0 59480.4 70968.6 73512.0 75194.2 64646.4 66009.3 66228.0 66429.7 66785.3 12016.0 12236.9 12292.6 12437.1 12517.7 44304.3 44589.3 45088.1 45226.5 45666.5 52948.7 53000.0 53436.2 54080.2 56143.7 13579.3 13894.7 13896.5 13932.7 14021.2 16565.6 16816.1 16854.8 17043.3 17124.0 16051.7 16095.4 16121.2 16171.0 16308.3 13808.1 14220.9 14298.6 14303.1 14825.6 14383.8 14540.8 14753.9 15006.0 15189.9 10859.3 11004.6 11131.4 11866.7 11939.1 11716.8 11848.4 12109.4 12128.9 12245.6 11:00:16 INFO - PROCESS | 3425 | -------- Summary: end -------- 11:00:16 INFO - PROCESS | 3425 | 11:00:17 INFO - TEST-INFO | 3425: exit 0 11:00:17 INFO - TEST-OK | dromaeo_css | took 1114841ms 11:00:17 INFO - TEST-START | kraken 11:00:17 INFO - operating with platform_type : linux_ 11:00:17 INFO - Initialising browser for kraken test... 11:00:17 INFO - TEST-INFO | started process 3728 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK4drsf/profile http://localhost:36541/getInfo.html) 11:00:21 INFO - PROCESS | 3728 | __metrics Screen width/height:1600/1200 11:00:21 INFO - PROCESS | 3728 | colorDepth:24 11:00:21 INFO - PROCESS | 3728 | Browser inner width/height: 1024/697 11:00:21 INFO - PROCESS | 3728 | __metrics 11:00:21 INFO - PROCESS | 3728 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:00:21 INFO - PROCESS | 3728 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:00:22 INFO - TEST-INFO | 3728: exit 0 11:00:22 INFO - Browser initialized. 11:00:22 INFO - Running cycle 1/1 for kraken test... 11:00:22 INFO - TEST-INFO | started process 3818 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpK4drsf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:00:34 INFO - PROCESS | 3818 | RSS: Main: 141647872 11:00:34 INFO - PROCESS | 3818 | 11:00:37 INFO - PROCESS | 3818 | Cycle 1(1): loaded http://localhost:36541/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36541/tests/kraken/driver.html?testName=audio-beat-detection) 11:00:37 INFO - PROCESS | 3818 | RSS: Main: 183513088 11:00:37 INFO - PROCESS | 3818 | 11:00:40 INFO - PROCESS | 3818 | Cycle 1(1): loaded http://localhost:36541/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36541/tests/kraken/driver.html?testName=audio-dft) 11:00:40 INFO - PROCESS | 3818 | RSS: Main: 174010368 11:00:40 INFO - PROCESS | 3818 | 11:00:43 INFO - PROCESS | 3818 | Cycle 1(1): loaded http://localhost:36541/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36541/tests/kraken/driver.html?testName=audio-fft) 11:00:44 INFO - PROCESS | 3818 | RSS: Main: 265355264 11:00:44 INFO - PROCESS | 3818 | 11:00:46 INFO - PROCESS | 3818 | Cycle 1(1): loaded http://localhost:36541/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36541/tests/kraken/driver.html?testName=audio-oscillator) 11:00:46 INFO - PROCESS | 3818 | RSS: Main: 171589632 11:00:46 INFO - PROCESS | 3818 | 11:00:49 INFO - PROCESS | 3818 | Cycle 1(1): loaded http://localhost:36541/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36541/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:00:49 INFO - PROCESS | 3818 | RSS: Main: 174391296 11:00:49 INFO - PROCESS | 3818 | 11:00:52 INFO - PROCESS | 3818 | Cycle 1(1): loaded http://localhost:36541/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36541/tests/kraken/driver.html?testName=imaging-darkroom) 11:00:53 INFO - PROCESS | 3818 | RSS: Main: 209170432 11:00:53 INFO - PROCESS | 3818 | 11:00:56 INFO - PROCESS | 3818 | Cycle 1(1): loaded http://localhost:36541/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36541/tests/kraken/driver.html?testName=imaging-desaturate) 11:00:57 INFO - PROCESS | 3818 | RSS: Main: 210923520 11:00:57 INFO - PROCESS | 3818 | 11:00:59 INFO - PROCESS | 3818 | Cycle 1(1): loaded http://localhost:36541/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36541/tests/kraken/driver.html?testName=json-parse-financial) 11:01:00 INFO - PROCESS | 3818 | RSS: Main: 211156992 11:01:00 INFO - PROCESS | 3818 | 11:01:02 INFO - PROCESS | 3818 | Cycle 1(1): loaded http://localhost:36541/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36541/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:01:02 INFO - PROCESS | 3818 | RSS: Main: 173842432 11:01:02 INFO - PROCESS | 3818 | 11:01:04 INFO - PROCESS | 3818 | Cycle 1(1): loaded http://localhost:36541/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36541/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:01:04 INFO - PROCESS | 3818 | RSS: Main: 180199424 11:01:04 INFO - PROCESS | 3818 | 11:01:06 INFO - PROCESS | 3818 | Cycle 1(1): loaded http://localhost:36541/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36541/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:01:07 INFO - PROCESS | 3818 | RSS: Main: 190656512 11:01:07 INFO - PROCESS | 3818 | 11:01:09 INFO - PROCESS | 3818 | Cycle 1(1): loaded http://localhost:36541/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36541/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:01:10 INFO - PROCESS | 3818 | RSS: Main: 199389184 11:01:10 INFO - PROCESS | 3818 | 11:01:13 INFO - PROCESS | 3818 | Cycle 1(1): loaded http://localhost:36541/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36541/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:01:13 INFO - PROCESS | 3818 | RSS: Main: 196046848 11:01:13 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | Cycle 1(1): loaded http://localhost:36541/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36541/tests/kraken/driver.html?testName=ai-astar) 11:01:15 INFO - PROCESS | 3818 | RSS: Main: 245751808 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | __start_tp_report 11:01:15 INFO - PROCESS | 3818 | _x_x_mozilla_page_load 11:01:15 INFO - PROCESS | 3818 | _x_x_mozilla_page_load_details 11:01:15 INFO - PROCESS | 3818 | |i|pagename|runs| 11:01:15 INFO - PROCESS | 3818 | |0;ai-astar;103;111;111;111;112;112;113;112;111;113 11:01:15 INFO - PROCESS | 3818 | |1;audio-beat-detection;139;116;114;152;118;117;115;114;131;116 11:01:15 INFO - PROCESS | 3818 | |2;audio-dft;161;162;161;159;159;158;159;160;159;162 11:01:15 INFO - PROCESS | 3818 | |3;audio-fft;73;75;74;110;77;75;76;75;90;74 11:01:15 INFO - PROCESS | 3818 | |4;audio-oscillator;81;82;105;81;83;82;99;83;82;94 11:01:15 INFO - PROCESS | 3818 | |5;imaging-gaussian-blur;113;113;113;113;113;114;113;113;112;113 11:01:15 INFO - PROCESS | 3818 | |6;imaging-darkroom;147;146;146;145;147;146;146;147;146;147 11:01:15 INFO - PROCESS | 3818 | |7;imaging-desaturate;93;94;94;94;93;93;94;94;94;94 11:01:15 INFO - PROCESS | 3818 | |8;json-parse-financial;54;56;55;54;55;57;58;55;54;58 11:01:15 INFO - PROCESS | 3818 | |9;json-stringify-tinderbox;53;53;53;54;53;54;52;53;53;53 11:01:15 INFO - PROCESS | 3818 | |10;stanford-crypto-aes;79;75;77;78;79;78;83;75;83;85 11:01:15 INFO - PROCESS | 3818 | |11;stanford-crypto-ccm;121;120;122;120;125;118;118;124;158;123 11:01:15 INFO - PROCESS | 3818 | |12;stanford-crypto-pbkdf2;161;159;160;155;158;162;159;160;162;163 11:01:15 INFO - PROCESS | 3818 | |13;stanford-crypto-sha256-iterative;60;59;59;58;59;61;61;59;60;58 11:01:15 INFO - PROCESS | 3818 | __end_tp_report 11:01:15 INFO - PROCESS | 3818 | __start_cc_report 11:01:15 INFO - PROCESS | 3818 | _x_x_mozilla_cycle_collect,1695 11:01:15 INFO - PROCESS | 3818 | __end_cc_report 11:01:15 INFO - PROCESS | 3818 | __startTimestamp1494871275818__endTimestamp 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | ------- Summary: start ------- 11:01:15 INFO - PROCESS | 3818 | Number of tests: 14 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:2.88 (2.6%) stddev-sans-first:0.83 11:01:15 INFO - PROCESS | 3818 | Values: 103.0 111.0 111.0 111.0 112.0 112.0 113.0 112.0 111.0 113.0 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | [#1] audio-beat-detection Cycles:10 Average:123.20 Median:117.50 stddev:13.10 (11.2%) stddev-sans-first:12.59 11:01:15 INFO - PROCESS | 3818 | Values: 139.0 116.0 114.0 152.0 118.0 117.0 115.0 114.0 131.0 116.0 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.50 stddev:1.41 (0.9%) stddev-sans-first:1.45 11:01:15 INFO - PROCESS | 3818 | Values: 161.0 162.0 161.0 159.0 159.0 158.0 159.0 160.0 159.0 162.0 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | [#3] audio-fft Cycles:10 Average:79.90 Median:75.00 stddev:11.65 (15.5%) stddev-sans-first:12.08 11:01:15 INFO - PROCESS | 3818 | Values: 73.0 75.0 74.0 110.0 77.0 75.0 76.0 75.0 90.0 74.0 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | [#4] audio-oscillator Cycles:10 Average:87.20 Median:82.50 stddev:8.79 (10.7%) stddev-sans-first:9.03 11:01:15 INFO - PROCESS | 3818 | Values: 81.0 82.0 105.0 81.0 83.0 82.0 99.0 83.0 82.0 94.0 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:113.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 11:01:15 INFO - PROCESS | 3818 | Values: 113.0 113.0 113.0 113.0 113.0 114.0 113.0 113.0 112.0 113.0 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 11:01:15 INFO - PROCESS | 3818 | Values: 147.0 146.0 146.0 145.0 147.0 146.0 146.0 147.0 146.0 147.0 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | [#7] imaging-desaturate Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 11:01:15 INFO - PROCESS | 3818 | Values: 93.0 94.0 94.0 94.0 93.0 93.0 94.0 94.0 94.0 94.0 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:55.50 stddev:1.58 (2.8%) stddev-sans-first:1.56 11:01:15 INFO - PROCESS | 3818 | Values: 54.0 56.0 55.0 54.0 55.0 57.0 58.0 55.0 54.0 58.0 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | [#9] json-stringify-tinderbox Cycles:10 Average:53.10 Median:53.00 stddev:0.57 (1.1%) stddev-sans-first:0.60 11:01:15 INFO - PROCESS | 3818 | Values: 53.0 53.0 53.0 54.0 53.0 54.0 52.0 53.0 53.0 53.0 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:79.00 stddev:3.43 (4.3%) stddev-sans-first:3.63 11:01:15 INFO - PROCESS | 3818 | Values: 79.0 75.0 77.0 78.0 79.0 78.0 83.0 75.0 83.0 85.0 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:122.50 stddev:11.86 (9.7%) stddev-sans-first:12.50 11:01:15 INFO - PROCESS | 3818 | Values: 121.0 120.0 122.0 120.0 125.0 118.0 118.0 124.0 158.0 123.0 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.90 Median:160.50 stddev:2.33 (1.5%) stddev-sans-first:2.44 11:01:15 INFO - PROCESS | 3818 | Values: 161.0 159.0 160.0 155.0 158.0 162.0 159.0 160.0 162.0 163.0 11:01:15 INFO - PROCESS | 3818 | 11:01:15 INFO - PROCESS | 3818 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.40 Median:59.50 stddev:1.07 (1.8%) stddev-sans-first:1.12 11:01:15 INFO - PROCESS | 3818 | Values: 60.0 59.0 59.0 58.0 59.0 61.0 61.0 59.0 60.0 58.0 11:01:15 INFO - PROCESS | 3818 | -------- Summary: end -------- 11:01:15 INFO - PROCESS | 3818 | 11:01:16 INFO - TEST-INFO | 3818: exit 0 11:01:16 INFO - TEST-OK | kraken | took 59563ms 11:01:16 INFO - SUITE-END | took 1174s 11:01:16 INFO - Completed test suite (00:19:34) 11:01:16 INFO - javascript benchmark 11:01:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7804.276192480424, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3331.050650564589, 3375.206826395798, 3405.3189362127573, 3440.6559344065595, 3481.5125882376474, 5716.428357164282, 5747.626404283718, 5777.711114444277, 5784.1902133701315, 5786.305643322808, 5783.334916484606, 5832.912506312408, 5836.270466191726, 5839.532837373011, 5845.473907348338, 5157.664751791141, 5173.249878767577, 5187.922181167285, 5202.895942081156, 5240.161574148137, 5028.547430731233, 5064.290999260106, 5255.316808814849, 5256.9474305256845, 5263.999840030382, 5127.461616530267, 5133.358330208724, 5160.948390516084, 5167.715775632339, 5171.0, 5237.109691352479, 5242.135047717122, 5245.370555533323, 5247.317848679685, 5274.235235890813, 4997.925031124536, 5108.208227724708, 5168.0, 5209.114450543379, 5221.843344699665, 5655.0, 5738.971305143489, 5839.824805255849, 5841.85395365115, 5844.47399734026, 6183.319834818167, 6185.103160041769, 6215.471684906788, 6229.968850155766, 6230.190075290228, 5996.370381110008, 6259.593126446766, 6260.874782504325, 6284.27730810958, 6288.276848162474, 5703.31560212773, 5715.0855863062125, 5744.569157313218, 5805.506531944748, 5813.418658134237, 6286.528510361742, 6307.463865571386, 6313.842153946188, 6315.810525684236, 6319.146915166394, 6015.187949626832, 6064.272287325461, 6066.332703402622, 6069.2716873975405, 6075.119107729354, 6000.669963151981, 6009.939900600938, 6113.2330707408255, 6115.453310713017, 6130.325664176937, 6136.140940268366, 6161.137440758297, 6166.0, 6182.443580077815, 6186.536009799283, 6018.608790428608, 6033.788817391458, 6035.275766907999, 6035.486983606354, 6193.287771906288, 4617.607503362185, 4662.0, 4664.836730714409, 4671.27595222741, 4688.437387513521, 4230.111676547959, 4239.59723826236, 4244.490661120685, 4247.787610619456, 4250.171216612731, 5816.709164541756, 5851.912221316684, 5876.912771137268, 5907.675077870673, 5954.047352423591, 6225.750969961151, 6228.5951413158, 6258.43674069336, 6258.624482531062, 6259.874802503924, 5949.732262048218, 5958.880822383528, 5970.34326224115, 5973.163757074013, 5987.0, 5121.129408000649, 5146.511081447256, 5239.659422137625, 5272.525472707474, 5279.498447647467, 6516.609003459807, 6517.608943463408, 6518.57629254097, 6524.380183882524, 6538.378854008862, 6170.53720970938, 6262.655553944577, 6263.812085637256, 6269.874602508015, 6274.6548939808745, 6276.686165691606, 6288.748450062129, 6540.803775886731, 6550.934490655032, 6557.803265902031, 6111.96944015277, 6119.3268740437625, 6137.438273608381, 6145.678679084019, 6155.446009858955, 6260.906086408617, 6268.905966410416, 6275.52933529978, 6277.5605707600025, 6279.152314437675, 6052.122442245938, 6057.515398768, 6099.725512352044, 6111.96944015277, 6122.816315510542, 5928.970355148197, 5959.553033522417, 6001.987346891948, 6061.242344706911, 6070.878582428415, 6065.484433823174, 6069.2716873975405, 6072.0, 6081.331053584014, 6110.847228819315, 5984.820455386345, 6043.063325184604, 6112.847178820565, 6114.2357205349335, 6116.8776624468155, 5812.389699081536, 5918.733656985532, 5969.761209551743, 5971.970140149271, 5974.9103763444455], "value": 5698.525904821738, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.4167511853433, 813.4590497319283, 835.7409203147025, 902.210565754964, 916.7478943290598, 31018.0, 43505.78247108764, 43970.34044489327, 44045.33931991023, 44064.0, 42337.78831105848, 42480.150396992045, 42611.14777704444, 46732.0, 46898.76550617251, 7603.0, 8918.955405222981, 9037.59330830111, 9043.14090161437, 9044.321675874302, 7459.664315105833, 10561.788764224711, 10716.732081697943, 10723.624673136443, 10727.517261723242, 10835.945820270927, 10851.186161037915, 10866.67399977998, 10877.782444351109, 10882.61910833121, 10762.139028877671, 10776.084032857216, 10807.243492955497, 10813.945930270338, 10828.079613232885, 10522.631707890263, 10542.10392116671, 10562.735931601772, 10565.788684226272, 10567.313124646951, 41542.169156617, 41705.79147104245, 41806.581934180576, 41812.0, 41825.79087104545, 4286.828526858922, 4350.630196433307, 4351.456067991501, 4476.037651904823, 4496.9325460118125, 29555.852220738972, 29666.516674166207, 29716.257093572618, 29775.70224297751, 31363.529547056813, 4225.260579398624, 4335.284678028122, 4338.457692788402, 4409.11817636474, 4438.977805110953, 240590.0, 242144.99999999822, 242377.0, 242638.0, 242697.0, 46291.76854115708, 46401.30398044032, 46436.30345544819, 46467.0, 46498.5350146501, 48077.0, 48088.5191148091, 48121.0, 48149.03701925942, 48191.0, 15679.216039197994, 15958.1223032732, 15962.0, 15967.520974370787, 15968.441104561516, 12208.572699955637, 12325.260484370967, 13866.930665346608, 13958.651033724238, 13971.58085257444, 12456.0035197184, 12528.123031387715, 12738.936305318413, 12921.676958076123, 13008.804867926989, 5429.918551221735, 5577.944220557823, 5586.301712285965, 5610.85972850682, 5640.5769567281795, 1015.0914931136739, 1017.5319353097658, 1018.5518371916334, 1022.5500779656927, 1025.36939782035, 29347.266318341786, 29395.55906661402, 29685.554716679268, 29990.55014174789, 30205.395892082037, 1129.6441620889557, 1184.6209213051914, 1194.3490797515376, 1200.351810022578, 1239.9690007749698, 13231.27228002469, 13591.357798437833, 13596.116252443757, 13814.516491922734, 13960.650983725489, 363838.0, 364951.0, 367335.0, 368039.0, 368166.0, 48129.75935120372, 48683.0, 48695.756521217874, 48723.26915096276, 48729.756351218726, 1435.798988141661, 1468.4199741102204, 1478.911265324084, 1495.2673190136973, 1508.351408894186, 27558.448831023667, 28262.43475130445, 28556.714432856235, 29346.55980160342, 29466.558001629564, 24330.026798927844, 24445.26664200077, 24481.51036979286, 24498.265052048468, 24516.87741561281, 321280.0, 322013.0, 322983.0, 323372.0, 324732.0, 25770.96916123334, 26126.608100878122, 30644.387112258075, 31112.066638000895, 31562.68437315689, 36848.81575592105, 37002.0, 37190.44214336841, 37547.43678844765, 37984.81007594944, 30248.243793905327, 30634.693653064074, 31031.224219394695, 31351.84324078365, 31499.842500788265, 30101.69898300989, 30179.396412072074, 30453.0, 30486.237844054078, 31051.378972420876, 33831.4925276126, 34374.828125860215, 34375.0, 34812.477812833335, 34853.12867178341, 29032.854835725688, 29073.0, 29308.41383172282, 29324.266893327836, 29665.25836854096, 23272.53454930858, 24078.51842963166, 24268.75731242665, 24653.260402187963, 24716.0], "value": 18215.773966418943, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.9331646911197, 469.8543451530026, 476.50919552860523, 480.2795806290564, 487.20585445723464, 20125.698114528277, 21243.893780531114, 21299.04154313056, 21648.89175554122, 21782.019809108588, 22045.669314960287, 22085.116595336167, 22328.553428931413, 22439.77560224401, 22613.0, 2072.5025993761537, 2073.0153177240763, 2349.4713689419914, 2814.9718502815017, 6686.966565167192, 6578.572392794453, 6698.93301066988, 6776.491763117761, 6776.966115169417, 6788.11754471918, 6686.83282917926, 6750.62871542065, 6797.864042719143, 6799.966000169992, 6812.557183783063, 6606.273309935903, 6640.501962352819, 6702.564333318319, 6705.631190284533, 6710.899336509956, 6493.707783149723, 6520.739170433177, 6534.869302613969, 6537.444317233041, 6540.3132671069325, 24395.268141955592, 24550.0, 24772.752272477224, 24954.0, 25218.495630087295, 3155.384699983504, 3237.5629290045777, 3249.5938007749032, 3327.617324007722, 3332.3002169544425, 15599.844001559952, 16273.0, 16470.0, 16663.583410414838, 16905.49283521484, 2330.4290448839974, 2419.8911049002663, 2478.7397323280975, 2484.63972723954, 2515.5472015037285, 96054.0, 97080.51459742726, 98416.50791746067, 99074.5046274764, 99270.0, 17841.7323740144, 17890.821091789177, 18330.541736456427, 18416.81583184151, 18633.440996770118, 32873.84941527035, 32971.340573188405, 32988.0, 33019.504707429405, 33046.3390732184, 11119.885642312234, 11207.943960280147, 11283.830742538868, 11313.094952403788, 11445.771084578262, 9547.952260238655, 9582.035682685033, 9584.952075239578, 9751.707448776548, 9824.607015719292, 9548.0, 10155.39067655943, 10230.386176829414, 10284.023017813295, 10398.532066057045, 3734.3464893643504, 3848.191879705237, 3892.7576601670858, 3904.882853514399, 3909.3354129798004, 981.8085473332775, 995.9004099589984, 1001.4592120254981, 1010.6159659329555, 1020.7193021919031, 20707.585848283252, 21115.155393784076, 21143.471413214793, 21247.78752212489, 21382.786172138392, 1020.5917632946881, 1039.7244730146517, 1045.9738506537396, 1046.842973553973, 1054.467493915583, 11243.55025798959, 11940.641780746591, 12104.81842772359, 12199.817002744965, 12269.141160118885, 650256.0, 656707.0, 661376.0, 664896.0, 665448.0, 87380.0, 87640.0, 89113.55443222742, 90042.0, 90549.54725226463, 1302.433441452971, 1316.8814806667447, 1325.2181213084327, 1332.5202926946292, 1341.1483707845393, 17613.29546818164, 17870.91064544669, 18190.181441835415, 18408.71607776479, 18527.444176674722, 41106.32808867414, 41313.58686413097, 41378.586214138675, 41657.37513937354, 41682.0, 50376.24435633542, 51161.58209029897, 52043.73978130085, 52069.47930520798, 52949.470505294456, 12442.253464792142, 12562.87437125617, 12566.434510447234, 12637.2417654941, 12670.873291267339, 13400.597982060553, 13462.461501539829, 13689.110207836458, 13754.93122534421, 14202.644933876736, 11872.82190767122, 12052.397380131133, 12172.208806427554, 12214.26714397139, 12347.506099755909, 11006.944965275123, 11198.328100313682, 11339.773204536028, 11453.541858325907, 11756.82364764512, 12024.0, 12408.131430799758, 12424.316662583644, 12633.87366126327, 12767.361631918553, 10822.170992487929, 12562.0, 12593.496260149492, 12618.369081545703, 12739.426725797175, 12216.0, 12339.321337326533, 12408.193467424588, 12425.502979880703, 12451.439685214005], "value": 11089.265693243267, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.9283535823206, 1587.7221486239905, 1597.8801589880754, 1608.6219738361485, 1625.739881618941, 9389.201917836983, 11247.83128253077, 11571.13216508762, 11831.230969986966, 11864.7627047459, 11660.766784664302, 11673.241239319439, 11726.765464690701, 11753.941230293836, 11758.353290569015, 2089.9059542320633, 2171.8045375916163, 2404.8316617836713, 2439.231642032763, 2439.524292762912, 2474.257722683197, 2556.6676332076804, 2556.7187609362954, 2564.3204550794053, 2565.217608629367, 2492.2149522900318, 2503.5368456835454, 2509.7992160627105, 2520.7227205007434, 2559.795216382685, 2468.136152346682, 2494.750524947509, 2500.1499490173337, 2500.199936020474, 2500.6499090127395, 2369.9170529031403, 2372.41875740443, 2391.916282930089, 2397.700287464067, 2399.6280576510667, 11312.151588630803, 11657.0, 11734.295942243407, 11809.114316426303, 11809.881901180965, 5676.858078548069, 5849.532037436995, 5850.590458667895, 5860.7948721794955, 5861.765529378862, 3441.8795342162903, 3445.3626079175433, 3512.7892326460496, 3611.5485564304463, 3678.172411207457, 1292.3344477594046, 1319.1096010193178, 1340.2561578324026, 1392.3803907261272, 1393.881520070785, 17025.659486810317, 17154.914225428918, 17171.742423863652, 17183.312667493286, 17207.827921720873, 4835.8065677373215, 4937.728424936591, 4988.501149884983, 5044.066847633199, 5057.443681195065, 13249.0, 13249.337533123306, 13249.801252981213, 13252.469901203844, 13277.40251688657, 6087.269527656709, 6097.786577469856, 6104.786332478342, 6106.664133472702, 6107.572469927062, 4569.908601827945, 5232.659877107976, 5541.445855414507, 5687.7156142192725, 5696.0, 4128.453836290842, 4169.687273454503, 4191.476065491814, 4214.473190851144, 4232.915341693149, 1039.2257767962765, 1042.3329069395593, 1057.7620035491952, 1059.6397224943555, 1073.103958194916, 1072.340510585984, 1094.261373572835, 1095.7589330347312, 1098.3794156301665, 1108.3128460354633, 7564.432667549956, 7568.167501574932, 7618.352440042659, 7621.3902887768845, 7759.650815713307, 1061.6390427254616, 1098.7253186703324, 1100.3837850803575, 1115.0800589513683, 1126.1553834624033, 4458.331250312479, 4574.382458368144, 4579.725216486955, 4591.471980722193, 4737.549932756383, 14528.0, 15167.24163791806, 15175.392984280505, 15198.164100974553, 15206.315715792816, 14806.925965370105, 14895.77656335156, 14977.176255306063, 15031.774523382159, 15040.323185456462, 10558.0, 10866.0, 10874.945625271981, 11047.613333533449, 11061.889381106086, 6872.999785312259, 7073.363397294268, 7111.684338397413, 7244.818879528054, 7254.600996945219, 12831.0, 13509.459621615419, 13535.526256580972, 13695.794563081761, 13751.931240344134, 13686.384112715152, 13839.446422143, 13843.79234311466, 14027.0, 14033.578992630237, 8585.227329540372, 8802.735917922473, 9271.721848344561, 9493.620255189715, 9826.16477599412, 10786.838197426889, 10859.565617375532, 10950.945245273722, 10961.780764384508, 11029.669109926715, 9209.447433154031, 9346.392484488486, 9369.390989585656, 9473.952630236805, 9508.952455237679, 9126.224270937044, 9149.542522873962, 9228.35401521887, 9255.25957923351, 9311.115444032806, 8531.104234055334, 8654.567271636519, 8664.393492455467, 8692.261157801659, 8715.694950676696, 6678.966605166943, 6693.598384096969, 6703.731850725916, 6776.966115169393, 6828.624425656637, 6505.316941721051, 6643.833904152435, 6668.499862510428, 6755.560888542229, 6755.7973260802255], "value": 5497.326607044197, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.6181767318252, 449.4741152851164, 451.4582500998802, 453.25892166308086, 456.0559641542009, 5258.973705131474, 5281.234221037952, 5285.365756109262, 5298.1522956327, 5364.183803503162, 5129.384473863132, 5161.432242453337, 5239.214117882319, 5269.235960785683, 5303.284056652351, 4182.97908510457, 4190.748555086697, 4194.65770953295, 4196.223698615766, 4218.797642671839, 4184.602462766047, 4193.643540299078, 4197.874063778077, 4209.915801683965, 4217.15656868626, 4148.865235966708, 4215.283401821697, 4222.416593777333, 4229.577042295777, 4254.340577210522, 4018.7943616914954, 4138.448081969246, 4173.643565841114, 4176.4570605821355, 4188.717149153718, 3844.942325865114, 3849.615038496128, 3850.6137790395437, 3854.383298672199, 3873.86441474547, 5109.518239710479, 5139.897202055975, 5139.997700448376, 5142.922856157161, 5142.97428512855, 4843.66094373394, 4875.293082503017, 5066.6186892160995, 5121.667091639031, 5161.967606478682, 5444.3122631983815, 5513.972430137864, 5529.668219906818, 5537.695426751527, 5541.501264886179, 4586.472555656075, 4588.426446694163, 4589.495155532889, 4600.608948239404, 4609.792559334838, 5482.671039737628, 5654.465958775806, 5684.658920464744, 5690.40250773667, 5750.05124154514, 4696.788644510937, 4708.788104535304, 4721.409823772028, 4725.8025018366625, 4741.905161896742, 4783.904321913542, 4783.976080119577, 4795.280707893774, 4804.855854324376, 4813.470518243061, 4844.632980078812, 5078.187490001582, 5079.187330027178, 5082.364704411949, 5091.9236211456855, 5456.208849716769, 5461.290032295776, 5476.424975377528, 5480.671159730429, 5486.6982315973, 1556.750919852818, 1654.4788391656589, 1663.0853030832993, 1669.1570756767996, 1671.9498415047567, 1245.9314737689515, 1270.9364531773374, 1272.7390884868764, 1295.9870401296055, 1298.571471414431, 5295.232191332235, 5361.785528578891, 5385.3268341457315, 5400.378956419983, 5422.783088676488, 5593.860153496195, 5600.439956004367, 5616.213730077792, 5630.268065151504, 5640.351359593699, 5252.868678283074, 5294.735263236823, 5315.468453154731, 5378.462153784669, 5408.215288954636, 2930.575066615329, 2933.7212964768314, 2943.440746258204, 2946.219251898249, 2953.4388466191785, 5793.594448388657, 5819.82540523785, 5834.299884013945, 5841.620294680833, 5861.5346163459135, 5492.395836457987, 5531.502164805187, 5639.859003524863, 5717.656940583578, 5737.3688894221605, 5719.628224165416, 5759.798407055733, 5765.509931655856, 5776.826695199151, 5940.910886336622, 5572.526335261386, 5607.719614019365, 5632.605717599728, 5703.429657034263, 5712.714364281853, 5306.867328316823, 5481.917771233355, 5482.643628164156, 5546.4453554644215, 5582.246396736388, 5844.918690042356, 5852.858692554911, 5852.97572924745, 5855.238818953509, 5863.501602363676, 4509.9774501128595, 4578.175928332932, 4682.906341873211, 4716.457607375196, 4719.197736384892, 5517.834464966058, 5575.275214222125, 5575.470330318613, 5595.132754423071, 5670.0, 5463.48096930791, 5511.751971161227, 5514.310711161105, 5526.944730552804, 5535.612507124462, 5222.556082732858, 5427.430119837518, 5479.506844384025, 5492.709213334783, 5500.614956952975, 5291.809342897817, 5316.548093412103, 5343.572514198777, 5442.755076021668, 5460.726963651881, 5324.0150572144275, 5366.436524164907, 5496.883699775695, 5501.669899805865, 5524.502794748331, 5163.483651634807, 5335.2263921730355, 5367.1892956001675, 5372.409035006068, 5391.4338994405025], "value": 4394.204803976504, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [853.4495250563383, 857.6526506764762, 913.5021413329737, 920.638216869138, 921.7833809054871, 17904.91047544762, 18364.35724749634, 18422.447326580223, 18668.159932803024, 18868.339608113718, 17375.13124343781, 17761.555961100978, 17794.82205177948, 17910.820891791078, 17924.193411296492, 883.3021912689001, 892.3664198419131, 892.5581836990699, 892.5581836990699, 894.021046953588, 892.9866052009226, 902.8194361127768, 903.5617725403174, 903.8282726281985, 905.3617199874072, 956.3162338927665, 958.5542722633974, 959.3620242538739, 959.5106495687215, 961.5480724059681, 951.2057432044217, 954.2127744610689, 955.8375076237056, 955.9665411710557, 960.2893858544627, 692.8198668346216, 698.6122701900443, 699.1400577289962, 699.2064007351642, 699.5837476701355, 19099.809001910082, 19199.80800191994, 19328.0, 19424.902875485674, 19681.606367872562, 17372.56568585783, 17421.128943552772, 17620.647587048188, 17706.380276690383, 18282.08589570516, 12439.875601243963, 12756.425960831784, 12774.744505109938, 12812.10315277931, 12898.80651790224, 4203.26442872496, 4220.556841531657, 4258.6380157686635, 4301.5698430156735, 4358.128374325116, 49830.13559948988, 53578.46421535774, 66581.66709166471, 83339.7499037521, 90809.54595227048, 14084.0, 14684.339204735812, 14738.705225895423, 14749.262536873113, 14801.0, 26368.73631263701, 26376.736232637428, 26399.604005939927, 26413.0, 26974.0, 9693.854592181124, 9766.121049105615, 9773.951130244302, 9785.804283914282, 9815.36200146988, 5168.121419358718, 5217.217417387422, 5229.346331708536, 5237.476252374809, 5276.129438642658, 3932.036651020518, 4039.0710136668145, 4039.4546736190828, 4042.2521833460905, 4044.2315959967514, 684.852756657321, 690.0856365315976, 703.6024646074951, 703.968321425537, 706.9363757261871, 818.3248819723751, 841.2176675691543, 843.1399972028497, 850.4047166983137, 862.1292494580393, 11851.585194518151, 12067.75864482723, 12080.879191207976, 12174.016414960612, 12280.815787763191, 758.3705524414722, 765.9195784442664, 785.2500861677199, 790.707438247852, 795.7254747112237, 5318.7074710891275, 5527.198556209327, 5654.349749778746, 5667.631603945731, 5696.11710184922, 83320.16679833246, 84730.15269847345, 85657.57171214104, 85772.14227857642, 87030.56484717662, 46110.0, 59480.40519594749, 70968.64515677388, 73512.0, 75194.24805751981, 64646.35353646497, 66009.33990660128, 66228.0, 66429.6678516614, 66785.33214667888, 12016.038716902453, 12236.898679118922, 12292.569760058355, 12437.130306301731, 12517.687057823627, 44304.33543496914, 44589.33116003198, 45088.0982380344, 45226.54773452223, 45666.543334567556, 52948.735256323474, 53000.0, 53436.19845702395, 54080.18879716886, 56143.71928140333, 13579.253141077335, 13894.722105558034, 13896.51362202318, 13932.721345573234, 14021.158730476203, 16565.58586035359, 16816.075115868745, 16854.831451685328, 17043.31826726917, 17124.0, 16051.678966420373, 16095.436659716854, 16121.193940303172, 16171.0, 16308.266128024505, 13808.1024733392, 14220.94846266759, 14298.642533936734, 14303.141811491343, 14825.555233343017, 14383.784243236569, 14540.781888271473, 14753.852461475677, 15006.0, 15189.924050379677, 10859.34843909368, 11004.559817607205, 11131.443427828739, 11866.703332416759, 11939.104567157672, 11716.765664686829, 11848.407579621156, 12109.394530273627, 12128.93935530352, 12245.571405000783], "value": 8124.832185056171, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1446.3000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 111.0, 111.0, 111.0, 112.0, 112.0, 113.0, 112.0, 111.0, 113.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 116.0, 114.0, 152.0, 118.0, 117.0, 115.0, 114.0, 131.0, 116.0], "value": 123.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 161.0, 159.0, 159.0, 158.0, 159.0, 160.0, 159.0, 162.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 74.0, 110.0, 77.0, 75.0, 76.0, 75.0, 90.0, 74.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 105.0, 81.0, 83.0, 82.0, 99.0, 83.0, 82.0, 94.0], "value": 87.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 112.0, 113.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 145.0, 147.0, 146.0, 146.0, 147.0, 146.0, 147.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 55.0, 54.0, 55.0, 57.0, 58.0, 55.0, 54.0, 58.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 54.0, 53.0, 54.0, 52.0, 53.0, 53.0, 53.0], "value": 53.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 75.0, 77.0, 78.0, 79.0, 78.0, 83.0, 75.0, 83.0, 85.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 120.0, 122.0, 120.0, 125.0, 118.0, 118.0, 124.0, 158.0, 123.0], "value": 124.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 160.0, 155.0, 158.0, 162.0, 159.0, 160.0, 162.0, 163.0], "value": 159.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 58.0, 59.0, 61.0, 61.0, 59.0, 60.0, 58.0], "value": 59.4, "unit": "score"}]}]} 11:01:17 INFO - Return code: 0 11:01:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:01:17 INFO - # TBPL SUCCESS # 11:01:17 INFO - Running post-action listener: _resource_record_post_action 11:01:17 INFO - [mozharness: 2017-05-15 18:01:17.162554Z] Finished run-tests step (success) 11:01:17 INFO - Running post-run listener: _resource_record_post_run 11:01:17 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 7741440; Write bytes: 328900608; Read time: 1868; Write time: 184064 11:01:17 INFO - TinderboxPrint: CPU usage
13.1% 11:01:17 INFO - TinderboxPrint: I/O read bytes / time
7,741,440 / 1,868 11:01:17 INFO - TinderboxPrint: I/O write bytes / time
328,900,608 / 184,064 11:01:17 INFO - TinderboxPrint: CPU idle
8,244.9 (86.9%) 11:01:17 INFO - TinderboxPrint: CPU user
1,200.8 (12.6%) 11:01:17 INFO - TinderboxPrint: Swap in / out
0 / 0 11:01:17 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 4096; Write bytes: 103215104; Read time: 176; Write time: 62020 11:01:17 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 5398528; Write bytes: 225685504; Read time: 1128; Write time: 122044 11:01:17 INFO - Running post-run listener: _upload_blobber_files 11:01:17 INFO - Blob upload gear active. 11:01:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:01:17 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 11:01:17 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-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:01:17 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-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:01:18 INFO - (blobuploader) - INFO - Open directory for files ... 11:01:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:01:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:01:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:01:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:01:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:01:19 INFO - (blobuploader) - INFO - Done attempting. 11:01:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:01:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:01:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:01:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:01:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:01:20 INFO - (blobuploader) - INFO - Done attempting. 11:01:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:01:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:01:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:01:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:01:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:01:21 INFO - (blobuploader) - INFO - Done attempting. 11:01:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:01:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:01:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:01:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:01:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:01:24 INFO - (blobuploader) - INFO - Done attempting. 11:01:24 INFO - (blobuploader) - INFO - Iteration through files over. 11:01:24 INFO - Return code: 0 11:01:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:01:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:01:24 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/042deb07787d8fe26bbb338d1c8763bb76fa662d4d25743176a7aec3b5af0cf993da3783f27cd0c5958bbc9ddf61f98bbb8ff8806281e77e5ae2291aef8d0aeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fb64a5c9f52a43e7feff1a5d2f94631b0dc78676da0272f98a872a4943b31f6e4f3bed7dec03f94130351b1dbc1570b80a61448863d76ca49086e76c2695a97d", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d3d2f00cd9c7e232308cca7df8cddc7dbc1edaeafb256e7a1426bb611032f8b8a836ef89f5443209301637209b9b44316ce72067c35f2fb388c84e9dd689579e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9bbaed7c1d11da15389e4d810fef11f2b9c77c10e9dc5b880ee13173fcb07021599513adc39c5506a5d8a404449e2454470947368d4bc6a206ec4b3f5faee20b"} 11:01:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:01:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:01:24 INFO - Contents: 11:01:24 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/042deb07787d8fe26bbb338d1c8763bb76fa662d4d25743176a7aec3b5af0cf993da3783f27cd0c5958bbc9ddf61f98bbb8ff8806281e77e5ae2291aef8d0aeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fb64a5c9f52a43e7feff1a5d2f94631b0dc78676da0272f98a872a4943b31f6e4f3bed7dec03f94130351b1dbc1570b80a61448863d76ca49086e76c2695a97d", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d3d2f00cd9c7e232308cca7df8cddc7dbc1edaeafb256e7a1426bb611032f8b8a836ef89f5443209301637209b9b44316ce72067c35f2fb388c84e9dd689579e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9bbaed7c1d11da15389e4d810fef11f2b9c77c10e9dc5b880ee13173fcb07021599513adc39c5506a5d8a404449e2454470947368d4bc6a206ec4b3f5faee20b"} 11:01:24 INFO - Running post-run listener: copy_logs_to_upload_dir 11:01:24 INFO - Copying logs to upload dir... 11:01:24 INFO - mkdir: /builds/slave/test/build/upload/logs 11:01:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.731110 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-05-15 11:01:24.575145) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 11:01:24.582275) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/042deb07787d8fe26bbb338d1c8763bb76fa662d4d25743176a7aec3b5af0cf993da3783f27cd0c5958bbc9ddf61f98bbb8ff8806281e77e5ae2291aef8d0aeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fb64a5c9f52a43e7feff1a5d2f94631b0dc78676da0272f98a872a4943b31f6e4f3bed7dec03f94130351b1dbc1570b80a61448863d76ca49086e76c2695a97d", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d3d2f00cd9c7e232308cca7df8cddc7dbc1edaeafb256e7a1426bb611032f8b8a836ef89f5443209301637209b9b44316ce72067c35f2fb388c84e9dd689579e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9bbaed7c1d11da15389e4d810fef11f2b9c77c10e9dc5b880ee13173fcb07021599513adc39c5506a5d8a404449e2454470947368d4bc6a206ec4b3f5faee20b"} build_url:https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011070 build_url: 'https://queue.taskcluster.net/v1/task/fcyPWT3kTbqACWVJQfWavQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/042deb07787d8fe26bbb338d1c8763bb76fa662d4d25743176a7aec3b5af0cf993da3783f27cd0c5958bbc9ddf61f98bbb8ff8806281e77e5ae2291aef8d0aeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fb64a5c9f52a43e7feff1a5d2f94631b0dc78676da0272f98a872a4943b31f6e4f3bed7dec03f94130351b1dbc1570b80a61448863d76ca49086e76c2695a97d", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d3d2f00cd9c7e232308cca7df8cddc7dbc1edaeafb256e7a1426bb611032f8b8a836ef89f5443209301637209b9b44316ce72067c35f2fb388c84e9dd689579e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/9bbaed7c1d11da15389e4d810fef11f2b9c77c10e9dc5b880ee13173fcb07021599513adc39c5506a5d8a404449e2454470947368d4bc6a206ec4b3f5faee20b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 11:01:24.610792) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:01:24.611143) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1494869567.454775-1672426536 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004341 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:01:24.693794) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 11:01:24.694156) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 11:01:24.694480) ========= ========= Total master_lag: 0.25 =========