builder: mozilla-beta_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-020 starttime: 1496054966.84 results: success (0) revision: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 03:49:26.835871) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 03:49:26.836343) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 03:49:26.836668) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 03:49:26.884944) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:49:26.885326) ========= 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=af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-29 03:49:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 21.3M=0.001s 2017-05-29 03:49:27 (21.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.230820 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:49:27.132711) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:49:27.133328) ========= 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=af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015292 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:49:27.184950) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:49:27.185360) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 715d88d0bd826c9e4a5403d6561a2fcc229dd208 --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 715d88d0bd826c9e4a5403d6561a2fcc229dd208 --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=af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-29 03:49:27,361 truncating revision to first 12 chars 2017-05-29 03:49:27,361 Setting DEBUG logging. 2017-05-29 03:49:27,361 attempt 1/10 2017-05-29 03:49:27,361 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/715d88d0bd82?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-29 03:49:27,836 unpacking tar archive at: mozilla-beta-715d88d0bd82/testing/mozharness/ program finished with exit code 0 elapsedTime=0.810406 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:49:28.010144) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 03:49:28.010470) ========= script_repo_revision: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 03:49:28.010878) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 03:49:28.011244) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 03:49:28.035318) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-05-29 03:49:28.035833) ========= /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=af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:49:28 INFO - MultiFileLogger online at 20170529 03:49:28 in /builds/slave/test 03:49:28 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 03:49:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:49:28 INFO - {'append_to_log': False, 03:49:28 INFO - 'base_work_dir': '/builds/slave/test', 03:49:28 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 03:49:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:49:28 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 03:49:28 INFO - 'buildbot_json_path': 'buildprops.json', 03:49:28 INFO - 'config_files': ('talos/linux_config.py',), 03:49:28 INFO - 'default_actions': ('clobber', 03:49:28 INFO - 'read-buildbot-config', 03:49:28 INFO - 'download-and-extract', 03:49:28 INFO - 'populate-webroot', 03:49:28 INFO - 'create-virtualenv', 03:49:28 INFO - 'install', 03:49:28 INFO - 'run-tests'), 03:49:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:49:28 INFO - 'download_minidump_stackwalk': True, 03:49:28 INFO - 'download_symbols': 'ondemand', 03:49:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:49:28 INFO - 'tooltool.py': '/tools/tooltool.py', 03:49:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:49:28 INFO - '/tools/misc-python/virtualenv.py')}, 03:49:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:49:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:49:28 INFO - 'gecko_profile': False, 03:49:28 INFO - 'gecko_profile_interval': 0, 03:49:28 INFO - 'installer_path': 'installer.exe', 03:49:28 INFO - 'log_level': 'info', 03:49:28 INFO - 'log_name': 'talos', 03:49:28 INFO - 'log_to_console': True, 03:49:28 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:49:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:49:28 INFO - 'opt_config_files': (), 03:49:28 INFO - 'pip_index': False, 03:49:28 INFO - 'suite': 'dromaeojs', 03:49:28 INFO - 'system_bits': '32', 03:49:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:49:28 INFO - 'title': 'talos-linux64-ix-020', 03:49:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:49:28 INFO - 'use_talos_json': True, 03:49:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:49:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:49:28 INFO - 'work_dir': 'build'} 03:49:28 INFO - [mozharness: 2017-05-29 10:49:28.304692Z] Running clobber step. 03:49:28 INFO - Running pre-action listener: _resource_record_pre_action 03:49:28 INFO - Running main action method: clobber 03:49:28 INFO - rmtree: /builds/slave/test/build 03:49:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:49:29 INFO - Running post-action listener: _resource_record_post_action 03:49:29 INFO - [mozharness: 2017-05-29 10:49:29.311158Z] Finished clobber step (success) 03:49:29 INFO - [mozharness: 2017-05-29 10:49:29.311257Z] Running read-buildbot-config step. 03:49:29 INFO - Running pre-action listener: _resource_record_pre_action 03:49:29 INFO - Running main action method: read_buildbot_config 03:49:29 INFO - Using buildbot properties: 03:49:29 INFO - { 03:49:29 INFO - "project": "", 03:49:29 INFO - "product": "firefox", 03:49:29 INFO - "who": "cbook@mozilla.com", 03:49:29 INFO - "installer_path": "public/build/target.tar.bz2", 03:49:29 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 03:49:29 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 03:49:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs", 03:49:29 INFO - "stage_platform": "linux64", 03:49:29 INFO - "basedir": "/builds/slave/test", 03:49:29 INFO - "buildnumber": 63, 03:49:29 INFO - "platform": "ubuntu64_hw", 03:49:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:49:29 INFO - "slavebuilddir": "test", 03:49:29 INFO - "taskId": "c-qRO0w7RmaydgjdIwQo7g", 03:49:29 INFO - "branch": "mozilla-beta", 03:49:29 INFO - "script_repo_revision": "production", 03:49:29 INFO - "revision": "715d88d0bd826c9e4a5403d6561a2fcc229dd208", 03:49:29 INFO - "slavename": "talos-linux64-ix-020", 03:49:29 INFO - "repo_path": "releases/mozilla-beta" 03:49:29 INFO - } 03:49:29 INFO - Finding installer, test and symbols from parent task. 03:49:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c-qRO0w7RmaydgjdIwQo7g'}, attempt #1 03:49:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c-qRO0w7RmaydgjdIwQo7g'}, attempt #1 03:49:30 INFO - Task dependencies: ZZEgqrGLS5ecJZxjaL_IWw 03:49:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw'}, attempt #1 03:49:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.tar.bz2 03:49:30 INFO - Set test_url: https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/test_packages.json 03:49:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.crashreporter-symbols.zip 03:49:30 INFO - Running post-action listener: _resource_record_post_action 03:49:30 INFO - [mozharness: 2017-05-29 10:49:30.661518Z] Finished read-buildbot-config step (success) 03:49:30 INFO - [mozharness: 2017-05-29 10:49:30.661735Z] Running download-and-extract step. 03:49:30 INFO - Running pre-action listener: _resource_record_pre_action 03:49:30 INFO - Running main action method: download_and_extract 03:49:30 INFO - mkdir: /builds/slave/test/build/tests 03:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:49:30 INFO - trying https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.test_packages.json 03:49:30 INFO - Downloading https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:49:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:49:31 INFO - Downloaded 986 bytes. 03:49:31 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:49:31 INFO - Using the following test package requirements: 03:49:31 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:49:31 INFO - u'common': [u'target.common.tests.zip'], 03:49:31 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:49:31 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:49:31 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:49:31 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:49:31 INFO - u'mozbase': [u'target.common.tests.zip'], 03:49:31 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:49:31 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:49:31 INFO - u'web-platform': [u'target.common.tests.zip', 03:49:31 INFO - u'target.web-platform.tests.zip'], 03:49:31 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:49:31 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:49:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.common.tests.zip 03:49:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:49:31 INFO - Fetch https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.common.tests.zip into memory 03:49:34 INFO - Expected file size: 59713097 03:49:34 INFO - Obtained file size: 59713097 03:49:43 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:49:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.common.tests.zip 03:49:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:49:43 INFO - Fetch https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.common.tests.zip into memory 03:49:45 INFO - Expected file size: 59713097 03:49:45 INFO - Obtained file size: 59713097 03:49:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.talos.tests.zip 03:49:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:49:59 INFO - Fetch https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.talos.tests.zip into memory 03:50:00 INFO - Expected file size: 14404461 03:50:00 INFO - Obtained file size: 14404461 03:50:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:00 INFO - trying https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.tar.bz2 03:50:00 INFO - Downloading https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:50:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:50:01 INFO - Downloaded 59696829 bytes. 03:50:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.tar.bz2 03:50:01 INFO - mkdir: /builds/slave/test/properties 03:50:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:50:01 INFO - Writing to file /builds/slave/test/properties/build_url 03:50:01 INFO - Contents: 03:50:01 INFO - build_url:https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.tar.bz2 03:50:01 INFO - Running post-action listener: _resource_record_post_action 03:50:01 INFO - Running post-action listener: set_extra_try_arguments 03:50:01 INFO - [mozharness: 2017-05-29 10:50:01.904938Z] Finished download-and-extract step (success) 03:50:01 INFO - [mozharness: 2017-05-29 10:50:01.905112Z] Running populate-webroot step. 03:50:01 INFO - Running pre-action listener: _resource_record_pre_action 03:50:01 INFO - Running main action method: populate_webroot 03:50:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:50:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:50:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:50:01 INFO - '--tppagecycles', 03:50:01 INFO - '7'], 03:50:01 INFO - 'tests': ['tsvgm']}}, 03:50:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:50:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:50:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:50:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:50:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:50:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:50:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:50:01 INFO - 'talos_options': ['--disable-e10s'], 03:50:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:50:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:50:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:50:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:50:01 INFO - 'talos_options': ['--disable-e10s'], 03:50:01 INFO - 'tests': ['damp', 'tps']}, 03:50:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:50:01 INFO - 'tests': ['damp', 'tps']}, 03:50:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:50:01 INFO - 'tests': ['dromaeo_dom']}, 03:50:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:50:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:50:01 INFO - 'tests': ['basic_compositor_video']}, 03:50:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:50:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:50:01 INFO - 'tests': ['a11yr', 03:50:01 INFO - 'ts_paint', 03:50:01 INFO - 'tpaint', 03:50:01 INFO - 'sessionrestore', 03:50:01 INFO - 'sessionrestore_no_auto_restore', 03:50:01 INFO - 'tabpaint']}, 03:50:01 INFO - 'other-e10s': {'tests': ['a11yr', 03:50:01 INFO - 'ts_paint', 03:50:01 INFO - 'tpaint', 03:50:01 INFO - 'sessionrestore', 03:50:01 INFO - 'sessionrestore_no_auto_restore', 03:50:01 INFO - 'tabpaint']}, 03:50:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:50:01 INFO - 'ts_paint', 03:50:01 INFO - 'tpaint', 03:50:01 INFO - 'sessionrestore', 03:50:01 INFO - 'sessionrestore_no_auto_restore', 03:50:01 INFO - 'tabpaint']}, 03:50:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:50:01 INFO - 'ts_paint', 03:50:01 INFO - 'tpaint', 03:50:01 INFO - 'sessionrestore', 03:50:01 INFO - 'sessionrestore_no_auto_restore', 03:50:01 INFO - 'tabpaint']}, 03:50:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:50:01 INFO - 'tests': ['a11yr', 03:50:01 INFO - 'ts_paint', 03:50:01 INFO - 'tpaint', 03:50:01 INFO - 'sessionrestore', 03:50:01 INFO - 'sessionrestore_no_auto_restore', 03:50:01 INFO - 'tabpaint']}, 03:50:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:50:01 INFO - 'tests': ['a11yr', 03:50:01 INFO - 'ts_paint', 03:50:01 INFO - 'tpaint', 03:50:01 INFO - 'sessionrestore', 03:50:01 INFO - 'sessionrestore_no_auto_restore', 03:50:01 INFO - 'tabpaint']}, 03:50:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:50:01 INFO - 'tests': ['tsvgx', 03:50:01 INFO - 'tsvgr_opacity', 03:50:01 INFO - 'tart', 03:50:01 INFO - 'tscrollx', 03:50:01 INFO - 'cart', 03:50:01 INFO - 'tsvg_static']}, 03:50:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:50:01 INFO - 'tsvgr_opacity', 03:50:01 INFO - 'tart', 03:50:01 INFO - 'tscrollx', 03:50:01 INFO - 'cart', 03:50:01 INFO - 'tsvg_static']}, 03:50:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:50:01 INFO - 'talos_options': ['--disable-e10s'], 03:50:01 INFO - 'tests': ['tp5o']}, 03:50:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:50:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:50:01 INFO - 'talos_options': ['--disable-e10s', 03:50:01 INFO - '--xperf_path', 03:50:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:50:01 INFO - 'tests': ['tp5n']}, 03:50:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:50:01 INFO - 'talos_options': ['--xperf_path', 03:50:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:50:01 INFO - 'tests': ['tp5n']}}, 03:50:01 INFO - 'talos.zip': {'path': '', 03:50:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:50:01 INFO - Running post-action listener: _resource_record_post_action 03:50:01 INFO - [mozharness: 2017-05-29 10:50:01.921666Z] Finished populate-webroot step (success) 03:50:01 INFO - [mozharness: 2017-05-29 10:50:01.921755Z] Running create-virtualenv step. 03:50:01 INFO - Running pre-action listener: _resource_record_pre_action 03:50:01 INFO - Running main action method: create_virtualenv 03:50:01 INFO - Creating virtualenv /builds/slave/test/build/venv 03:50:01 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 03:50:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:50:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:50:02 INFO - Using real prefix '/usr' 03:50:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:50:02 INFO - Installing distribute.............................................................................................................................................................................................done. 03:50:04 INFO - Installing pip.................done. 03:50:04 INFO - Return code: 0 03:50:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:50:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:50:04 INFO - Reading from file tmpfile_stdout 03:50:04 INFO - Output received: 03:50:04 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) 03:50:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:04 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 0x1e2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca400>, '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 0x20cb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb840>, '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': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:50:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:50:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:50:04 INFO - Using env: {'DISPLAY': ':0', 03:50:04 INFO - 'HOME': '/home/cltbld', 03:50:04 INFO - 'LANG': 'en_US.UTF-8', 03:50:04 INFO - 'LANGUAGE': 'en_US:en', 03:50:04 INFO - 'LOGNAME': 'cltbld', 03:50:04 INFO - 'MAIL': '/var/mail/cltbld', 03:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:04 INFO - 'MOZ_NO_REMOTE': '1', 03:50:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:50:04 INFO - 'NO_EM_RESTART': '1', 03:50:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:04 INFO - 'PWD': '/builds/slave/test', 03:50:04 INFO - 'SHELL': '/bin/bash', 03:50:04 INFO - 'SHLVL': '1', 03:50:04 INFO - 'TERM': 'linux', 03:50:04 INFO - 'TMOUT': '86400', 03:50:04 INFO - 'USER': 'cltbld', 03:50:04 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 03:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:04 INFO - '_': '/tools/buildbot/bin/python'} 03:50:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:04 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 03:50:04 INFO - Cleaning up... 03:50:04 INFO - Return code: 0 03:50:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:04 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 0x1e2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca400>, '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 0x20cb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb840>, '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': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:50:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:50:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:50:04 INFO - Using env: {'DISPLAY': ':0', 03:50:04 INFO - 'HOME': '/home/cltbld', 03:50:04 INFO - 'LANG': 'en_US.UTF-8', 03:50:04 INFO - 'LANGUAGE': 'en_US:en', 03:50:04 INFO - 'LOGNAME': 'cltbld', 03:50:04 INFO - 'MAIL': '/var/mail/cltbld', 03:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:04 INFO - 'MOZ_NO_REMOTE': '1', 03:50:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:50:04 INFO - 'NO_EM_RESTART': '1', 03:50:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:04 INFO - 'PWD': '/builds/slave/test', 03:50:04 INFO - 'SHELL': '/bin/bash', 03:50:04 INFO - 'SHLVL': '1', 03:50:04 INFO - 'TERM': 'linux', 03:50:04 INFO - 'TMOUT': '86400', 03:50:04 INFO - 'USER': 'cltbld', 03:50:04 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 03:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:04 INFO - '_': '/tools/buildbot/bin/python'} 03:50:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:05 INFO - Downloading/unpacking psutil>=3.1.1 03:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:50:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:50:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:50:06 INFO - Installing collected packages: psutil 03:50:06 INFO - Running setup.py install for psutil 03:50:06 INFO - building 'psutil._psutil_linux' extension 03:50:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:50:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:50:06 INFO - building 'psutil._psutil_posix' extension 03:50:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:50:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:50:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:50:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:50:06 INFO - Successfully installed psutil 03:50:06 INFO - Cleaning up... 03:50:06 INFO - Return code: 0 03:50:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:50:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:06 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 0x1e2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca400>, '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 0x20cb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb840>, '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': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:50:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:50:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:50:06 INFO - Using env: {'DISPLAY': ':0', 03:50:06 INFO - 'HOME': '/home/cltbld', 03:50:06 INFO - 'LANG': 'en_US.UTF-8', 03:50:06 INFO - 'LANGUAGE': 'en_US:en', 03:50:06 INFO - 'LOGNAME': 'cltbld', 03:50:06 INFO - 'MAIL': '/var/mail/cltbld', 03:50:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:06 INFO - 'MOZ_NO_REMOTE': '1', 03:50:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:50:06 INFO - 'NO_EM_RESTART': '1', 03:50:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:50:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:06 INFO - 'PWD': '/builds/slave/test', 03:50:06 INFO - 'SHELL': '/bin/bash', 03:50:06 INFO - 'SHLVL': '1', 03:50:06 INFO - 'TERM': 'linux', 03:50:06 INFO - 'TMOUT': '86400', 03:50:06 INFO - 'USER': 'cltbld', 03:50:06 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 03:50:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:06 INFO - '_': '/tools/buildbot/bin/python'} 03:50:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:50:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:50:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:50:08 INFO - Installing collected packages: mozsystemmonitor 03:50:08 INFO - Running setup.py install for mozsystemmonitor 03:50:08 INFO - Successfully installed mozsystemmonitor 03:50:08 INFO - Cleaning up... 03:50:08 INFO - Return code: 0 03:50:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:50:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:08 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 0x1e2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca400>, '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 0x20cb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb840>, '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': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:50:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:50:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:50:08 INFO - Using env: {'DISPLAY': ':0', 03:50:08 INFO - 'HOME': '/home/cltbld', 03:50:08 INFO - 'LANG': 'en_US.UTF-8', 03:50:08 INFO - 'LANGUAGE': 'en_US:en', 03:50:08 INFO - 'LOGNAME': 'cltbld', 03:50:08 INFO - 'MAIL': '/var/mail/cltbld', 03:50:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:08 INFO - 'MOZ_NO_REMOTE': '1', 03:50:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:50:08 INFO - 'NO_EM_RESTART': '1', 03:50:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:50:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:08 INFO - 'PWD': '/builds/slave/test', 03:50:08 INFO - 'SHELL': '/bin/bash', 03:50:08 INFO - 'SHLVL': '1', 03:50:08 INFO - 'TERM': 'linux', 03:50:08 INFO - 'TMOUT': '86400', 03:50:08 INFO - 'USER': 'cltbld', 03:50:08 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 03:50:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:08 INFO - '_': '/tools/buildbot/bin/python'} 03:50:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:08 INFO - Downloading/unpacking jsonschema==2.5.1 03:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:50:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:50:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:09 INFO - Downloading functools32-3.2.3-2.tar.gz 03:50:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:50:09 INFO - warning: no files found matching '*.txt' 03:50:09 INFO - no previously-included directories found matching 'build' 03:50:09 INFO - no previously-included directories found matching 'dist' 03:50:09 INFO - no previously-included directories found matching '.git*' 03:50:09 INFO - Installing collected packages: jsonschema, functools32 03:50:09 INFO - Running setup.py install for functools32 03:50:09 INFO - warning: no files found matching '*.txt' 03:50:09 INFO - no previously-included directories found matching 'build' 03:50:09 INFO - no previously-included directories found matching 'dist' 03:50:09 INFO - no previously-included directories found matching '.git*' 03:50:09 INFO - Successfully installed jsonschema functools32 03:50:09 INFO - Cleaning up... 03:50:09 INFO - Return code: 0 03:50:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:50:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:09 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 0x1e2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca400>, '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 0x20cb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb840>, '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': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:50:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:50:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:50:09 INFO - Using env: {'DISPLAY': ':0', 03:50:09 INFO - 'HOME': '/home/cltbld', 03:50:09 INFO - 'LANG': 'en_US.UTF-8', 03:50:09 INFO - 'LANGUAGE': 'en_US:en', 03:50:09 INFO - 'LOGNAME': 'cltbld', 03:50:09 INFO - 'MAIL': '/var/mail/cltbld', 03:50:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:09 INFO - 'MOZ_NO_REMOTE': '1', 03:50:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:50:09 INFO - 'NO_EM_RESTART': '1', 03:50:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:50:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:09 INFO - 'PWD': '/builds/slave/test', 03:50:09 INFO - 'SHELL': '/bin/bash', 03:50:09 INFO - 'SHLVL': '1', 03:50:09 INFO - 'TERM': 'linux', 03:50:09 INFO - 'TMOUT': '86400', 03:50:09 INFO - 'USER': 'cltbld', 03:50:09 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 03:50:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:09 INFO - '_': '/tools/buildbot/bin/python'} 03:50:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:50:10 INFO - Cleaning up... 03:50:10 INFO - Return code: 0 03:50:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:50: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')]} 03:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50: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')]} 03:50:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50: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', '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 0x1e2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca400>, '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 0x20cb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb840>, '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': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:50: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:50: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 blobuploader==1.2.4 03:50:10 INFO - Using env: {'DISPLAY': ':0', 03:50:10 INFO - 'HOME': '/home/cltbld', 03:50:10 INFO - 'LANG': 'en_US.UTF-8', 03:50:10 INFO - 'LANGUAGE': 'en_US:en', 03:50:10 INFO - 'LOGNAME': 'cltbld', 03:50:10 INFO - 'MAIL': '/var/mail/cltbld', 03:50:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:10 INFO - 'MOZ_NO_REMOTE': '1', 03:50:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:50:10 INFO - 'NO_EM_RESTART': '1', 03:50:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:50:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:10 INFO - 'PWD': '/builds/slave/test', 03:50:10 INFO - 'SHELL': '/bin/bash', 03:50:10 INFO - 'SHLVL': '1', 03:50:10 INFO - 'TERM': 'linux', 03:50:10 INFO - 'TMOUT': '86400', 03:50:10 INFO - 'USER': 'cltbld', 03:50:10 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 03:50:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:10 INFO - '_': '/tools/buildbot/bin/python'} 03:50:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:10 INFO - Downloading/unpacking blobuploader==1.2.4 03:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:11 INFO - Downloading blobuploader-1.2.4.tar.gz 03:50:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:50:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:50: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 03:50: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 03:50: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 03:50: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 03:50:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:50:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:50: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 03:50: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 03:50: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 03:50: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 03:50:12 INFO - Downloading docopt-0.6.1.tar.gz 03:50:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:50:12 INFO - Installing collected packages: blobuploader, requests, docopt 03:50:12 INFO - Running setup.py install for blobuploader 03:50:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:50:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:50:12 INFO - Running setup.py install for requests 03:50:12 INFO - Running setup.py install for docopt 03:50:12 INFO - Successfully installed blobuploader requests docopt 03:50:12 INFO - Cleaning up... 03:50:12 INFO - Return code: 0 03:50:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:12 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 0x1e2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca400>, '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 0x20cb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb840>, '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': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:50:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:50:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:50:12 INFO - Using env: {'DISPLAY': ':0', 03:50:12 INFO - 'HOME': '/home/cltbld', 03:50:12 INFO - 'LANG': 'en_US.UTF-8', 03:50:12 INFO - 'LANGUAGE': 'en_US:en', 03:50:12 INFO - 'LOGNAME': 'cltbld', 03:50:12 INFO - 'MAIL': '/var/mail/cltbld', 03:50:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:12 INFO - 'MOZ_NO_REMOTE': '1', 03:50:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:50:12 INFO - 'NO_EM_RESTART': '1', 03:50:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:50:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:12 INFO - 'PWD': '/builds/slave/test', 03:50:12 INFO - 'SHELL': '/bin/bash', 03:50:12 INFO - 'SHLVL': '1', 03:50:12 INFO - 'TERM': 'linux', 03:50:12 INFO - 'TMOUT': '86400', 03:50:12 INFO - 'USER': 'cltbld', 03:50:12 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 03:50:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:12 INFO - '_': '/tools/buildbot/bin/python'} 03:50:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:50:12 INFO - Running setup.py (path:/tmp/pip-DMtLoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:50:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:50:12 INFO - Running setup.py (path:/tmp/pip-stwqX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:50:13 INFO - Running setup.py (path:/tmp/pip-lFuqVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:50:13 INFO - Running setup.py (path:/tmp/pip-t0OOgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:50:13 INFO - Running setup.py (path:/tmp/pip-FAhRIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:50:13 INFO - Running setup.py (path:/tmp/pip-iRD4L_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:50:13 INFO - Running setup.py (path:/tmp/pip-ggz4FZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:50:13 INFO - Running setup.py (path:/tmp/pip-iYEEXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:50:13 INFO - Running setup.py (path:/tmp/pip-CpABbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:50:13 INFO - Running setup.py (path:/tmp/pip-E8ExjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:50:13 INFO - Running setup.py (path:/tmp/pip-9lGJYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:50:13 INFO - Running setup.py (path:/tmp/pip-by5VXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:50:13 INFO - Running setup.py (path:/tmp/pip-m1Wfyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:50:13 INFO - Running setup.py (path:/tmp/pip-1aNGym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:50:13 INFO - Running setup.py (path:/tmp/pip-osU5rQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:50:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:50:13 INFO - Running setup.py (path:/tmp/pip-RfonXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:50:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:50:14 INFO - Running setup.py (path:/tmp/pip-4e70eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:50:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:50:14 INFO - Running setup.py install for manifestparser 03:50:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:50:14 INFO - Running setup.py install for mozcrash 03:50:14 INFO - Running setup.py install for mozdebug 03:50:14 INFO - Running setup.py install for mozdevice 03:50:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:50:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:50:14 INFO - Running setup.py install for mozfile 03:50:14 INFO - Running setup.py install for mozhttpd 03:50:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:50:14 INFO - Running setup.py install for mozinfo 03:50:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:50:14 INFO - Running setup.py install for mozInstall 03:50:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:50:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:50:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:50:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:50:14 INFO - Running setup.py install for mozleak 03:50:15 INFO - Running setup.py install for mozlog 03:50:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:50:15 INFO - Running setup.py install for moznetwork 03:50:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:50:15 INFO - Running setup.py install for mozprocess 03:50:15 INFO - Running setup.py install for mozprofile 03:50:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:50:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:50:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:50:15 INFO - Running setup.py install for mozrunner 03:50:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:50:15 INFO - Running setup.py install for mozscreenshot 03:50:15 INFO - Running setup.py install for moztest 03:50:15 INFO - Running setup.py install for mozversion 03:50:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:50:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:50:15 INFO - Cleaning up... 03:50:15 INFO - Return code: 0 03:50:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:50:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:15 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 0x1e2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca400>, '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 0x20cb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb840>, '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': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:50:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:50:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:50:15 INFO - Using env: {'DISPLAY': ':0', 03:50:15 INFO - 'HOME': '/home/cltbld', 03:50:15 INFO - 'LANG': 'en_US.UTF-8', 03:50:15 INFO - 'LANGUAGE': 'en_US:en', 03:50:15 INFO - 'LOGNAME': 'cltbld', 03:50:15 INFO - 'MAIL': '/var/mail/cltbld', 03:50:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:15 INFO - 'MOZ_NO_REMOTE': '1', 03:50:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:50:15 INFO - 'NO_EM_RESTART': '1', 03:50:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:50:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:15 INFO - 'PWD': '/builds/slave/test', 03:50:15 INFO - 'SHELL': '/bin/bash', 03:50:15 INFO - 'SHLVL': '1', 03:50:15 INFO - 'TERM': 'linux', 03:50:15 INFO - 'TMOUT': '86400', 03:50:15 INFO - 'USER': 'cltbld', 03:50:15 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 03:50:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:15 INFO - '_': '/tools/buildbot/bin/python'} 03:50:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:50:16 INFO - Running setup.py (path:/tmp/pip-wsT1ms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:50:16 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:50:16 INFO - Running setup.py (path:/tmp/pip-bS4VrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:50:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:50:16 INFO - Running setup.py (path:/tmp/pip-rMHXFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:50:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:50:16 INFO - Running setup.py (path:/tmp/pip-4sLw8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:50:16 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)) 03:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:50:16 INFO - Running setup.py (path:/tmp/pip-rndJYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:50:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:50:16 INFO - Running setup.py (path:/tmp/pip-gZejiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:50:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:50:16 INFO - Running setup.py (path:/tmp/pip-s7VLQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:50:16 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)) 03:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:50:16 INFO - Running setup.py (path:/tmp/pip-tPDBM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:50:16 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)) 03:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:50:16 INFO - Running setup.py (path:/tmp/pip-SVpj5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:50:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:50:16 INFO - Running setup.py (path:/tmp/pip-u2E3QL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:50:16 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)) 03:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:50:16 INFO - Running setup.py (path:/tmp/pip-kx25T5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:50:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:50:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:50:16 INFO - Running setup.py (path:/tmp/pip-Tn3tPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:50:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:50:17 INFO - Running setup.py (path:/tmp/pip-2vpDi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:50:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:50:17 INFO - Running setup.py (path:/tmp/pip-W1HXrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:50:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:50:17 INFO - Running setup.py (path:/tmp/pip-L3OqNC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:50:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:50:17 INFO - Running setup.py (path:/tmp/pip-NtFkAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:50:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:50:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:50:17 INFO - Running setup.py (path:/tmp/pip-R3bMwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:50:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:50:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:50:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:50:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:50:17 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)) 03:50:17 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)) 03:50:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:50: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 03:50: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 03:50: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 03:50: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 03:50:18 INFO - Downloading blessings-1.6.tar.gz 03:50:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:50:18 INFO - Installing collected packages: blessings 03:50:18 INFO - Running setup.py install for blessings 03:50:18 INFO - Successfully installed blessings 03:50:18 INFO - Cleaning up... 03:50:18 INFO - Return code: 0 03:50:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:50:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:50:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:50:18 INFO - Reading from file tmpfile_stdout 03:50:18 INFO - Current package versions: 03:50:18 INFO - argparse == 1.2.1 03:50:18 INFO - blessings == 1.6 03:50:18 INFO - blobuploader == 1.2.4 03:50:18 INFO - docopt == 0.6.1 03:50:18 INFO - functools32 == 3.2.3-2 03:50:18 INFO - jsonschema == 2.5.1 03:50:18 INFO - manifestparser == 1.1 03:50:18 INFO - mozInstall == 1.12 03:50:18 INFO - mozcrash == 1.0 03:50:18 INFO - mozdebug == 0.1 03:50:18 INFO - mozdevice == 0.49 03:50:18 INFO - mozfile == 1.2 03:50:18 INFO - mozhttpd == 0.7 03:50:18 INFO - mozinfo == 0.9 03:50:18 INFO - mozleak == 0.1 03:50:18 INFO - mozlog == 3.4 03:50:18 INFO - moznetwork == 0.27 03:50:18 INFO - mozprocess == 0.25 03:50:18 INFO - mozprofile == 0.28 03:50:18 INFO - mozrunner == 6.13 03:50:18 INFO - mozscreenshot == 0.1 03:50:18 INFO - mozsystemmonitor == 0.3 03:50:18 INFO - moztest == 0.8 03:50:18 INFO - mozversion == 1.4 03:50:18 INFO - psutil == 3.1.1 03:50:18 INFO - requests == 1.2.3 03:50:18 INFO - wsgiref == 0.1.2 03:50:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:50: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')]} 03:50:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50: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')]} 03:50:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:18 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 0x1e2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca400>, '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 0x20cb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb840>, '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': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:50:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:50:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:50:18 INFO - Using env: {'DISPLAY': ':0', 03:50:18 INFO - 'HOME': '/home/cltbld', 03:50:18 INFO - 'LANG': 'en_US.UTF-8', 03:50:18 INFO - 'LANGUAGE': 'en_US:en', 03:50:18 INFO - 'LOGNAME': 'cltbld', 03:50:18 INFO - 'MAIL': '/var/mail/cltbld', 03:50:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:18 INFO - 'MOZ_NO_REMOTE': '1', 03:50:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:50:18 INFO - 'NO_EM_RESTART': '1', 03:50:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:50:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:18 INFO - 'PWD': '/builds/slave/test', 03:50:18 INFO - 'SHELL': '/bin/bash', 03:50:18 INFO - 'SHLVL': '1', 03:50:18 INFO - 'TERM': 'linux', 03:50:18 INFO - 'TMOUT': '86400', 03:50:18 INFO - 'USER': 'cltbld', 03:50:18 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 03:50:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:18 INFO - '_': '/tools/buildbot/bin/python'} 03:50:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:50:19 INFO - Cleaning up... 03:50:19 INFO - Return code: 0 03:50:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:50:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e2f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e6da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20cd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20ca400>, '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 0x20cb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb840>, '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': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:50:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:50:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:50:19 INFO - Using env: {'DISPLAY': ':0', 03:50:19 INFO - 'HOME': '/home/cltbld', 03:50:19 INFO - 'LANG': 'en_US.UTF-8', 03:50:19 INFO - 'LANGUAGE': 'en_US:en', 03:50:19 INFO - 'LOGNAME': 'cltbld', 03:50:19 INFO - 'MAIL': '/var/mail/cltbld', 03:50:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:19 INFO - 'MOZ_NO_REMOTE': '1', 03:50:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:50:19 INFO - 'NO_EM_RESTART': '1', 03:50:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:50:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:19 INFO - 'PWD': '/builds/slave/test', 03:50:19 INFO - 'SHELL': '/bin/bash', 03:50:19 INFO - 'SHLVL': '1', 03:50:19 INFO - 'TERM': 'linux', 03:50:19 INFO - 'TMOUT': '86400', 03:50:19 INFO - 'USER': 'cltbld', 03:50:19 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 03:50:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:19 INFO - '_': '/tools/buildbot/bin/python'} 03:50:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:50:19 INFO - Cleaning up... 03:50:19 INFO - Return code: 0 03:50:19 INFO - Running post-action listener: _resource_record_post_action 03:50:19 INFO - Running post-action listener: _start_resource_monitoring 03:50:19 INFO - Starting resource monitoring. 03:50:19 INFO - [mozharness: 2017-05-29 10:50:19.413323Z] Finished create-virtualenv step (success) 03:50:19 INFO - [mozharness: 2017-05-29 10:50:19.413835Z] Running install step. 03:50:19 INFO - Running pre-action listener: _resource_record_pre_action 03:50:19 INFO - Running main action method: install 03:50:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:50:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:50:19 INFO - Reading from file tmpfile_stdout 03:50:19 INFO - Detecting whether we're running mozinstall >=1.0... 03:50:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:50:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:50:19 INFO - Reading from file tmpfile_stdout 03:50:19 INFO - Output received: 03:50:19 INFO - Usage: mozinstall [options] installer 03:50:19 INFO - Options: 03:50:19 INFO - -h, --help show this help message and exit 03:50:19 INFO - -d DEST, --destination=DEST 03:50:19 INFO - Directory to install application into. [default: 03:50:19 INFO - "/builds/slave/test"] 03:50:19 INFO - --app=APP Application being installed. [default: firefox] 03:50:19 INFO - mkdir: /builds/slave/test/build/application 03:50:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:50:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:50:33 INFO - Reading from file tmpfile_stdout 03:50:33 INFO - Output received: 03:50:33 INFO - /builds/slave/test/build/application/firefox/firefox 03:50:33 INFO - Running post-action listener: _resource_record_post_action 03:50:33 INFO - [mozharness: 2017-05-29 10:50:33.795332Z] Finished install step (success) 03:50:33 INFO - [mozharness: 2017-05-29 10:50:33.795474Z] Running run-tests step. 03:50:33 INFO - Running pre-action listener: _resource_record_pre_action 03:50:33 INFO - Running main action method: run_tests 03:50:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:50:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:50:33 INFO - Python 2.7.3 03:50:33 INFO - Return code: 0 03:50:33 INFO - grabbing minidump binary from tooltool 03:50:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:50:33 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 0x20ca400>, '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 0x20cb3b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20cb840>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:50:33 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 03:50:33 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 03:50:33 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 03:50:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:50:33 INFO - Return code: 0 03:50:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:50:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:50:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:50:33 INFO - ENV: RUST_BACKTRACE is now 1 03:50:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:50:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:50:33 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/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 03:50:33 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/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-020 --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 03:50:33 INFO - Using env: {'DISPLAY': ':0', 03:50:33 INFO - 'HOME': '/home/cltbld', 03:50:33 INFO - 'LANG': 'en_US.UTF-8', 03:50:33 INFO - 'LANGUAGE': 'en_US:en', 03:50:33 INFO - 'LOGNAME': 'cltbld', 03:50:33 INFO - 'MAIL': '/var/mail/cltbld', 03:50:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:50:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:50:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:33 INFO - 'MOZ_NO_REMOTE': '1', 03:50:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:50:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:50:33 INFO - 'NO_EM_RESTART': '1', 03:50:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:50:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:33 INFO - 'PWD': '/builds/slave/test', 03:50:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:50:33 INFO - 'RUST_BACKTRACE': '1', 03:50:33 INFO - 'SHELL': '/bin/bash', 03:50:33 INFO - 'SHLVL': '1', 03:50:33 INFO - 'TERM': 'linux', 03:50:33 INFO - 'TMOUT': '86400', 03:50:33 INFO - 'USER': 'cltbld', 03:50:33 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008', 03:50:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:33 INFO - '_': '/tools/buildbot/bin/python'} 03:50:33 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/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 03:50:34 INFO - mozversion application_buildid: 20170529095024 03:50:34 INFO - mozversion application_changeset: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 03:50:34 INFO - mozversion application_display_name: Firefox 03:50:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:50:34 INFO - mozversion application_name: Firefox 03:50:34 INFO - mozversion application_remotingname: firefox 03:50:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 03:50:34 INFO - mozversion application_vendor: Mozilla 03:50:34 INFO - mozversion application_version: 54.0 03:50:34 INFO - mozversion platform_buildid: 20170529095024 03:50:34 INFO - mozversion platform_changeset: 715d88d0bd826c9e4a5403d6561a2fcc229dd208 03:50:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 03:50:34 INFO - mozversion platform_version: 54.0 03:50:34 INFO - using testdate: 1496055034 03:50:34 INFO - actual date: 1496055034 03:50:34 INFO - starting webserver on 'localhost:51329' 03:50:34 INFO - SUITE-START | Running 2 tests 03:50:34 INFO - TEST-START | dromaeo_css 03:50:34 INFO - operating with platform_type : linux_ 03:50:34 INFO - Initialising browser for dromaeo_css test... 03:50:34 INFO - TEST-INFO | started process 6056 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9A7q6W/profile http://localhost:51329/getInfo.html) 03:50:41 INFO - PROCESS | 6056 | __metrics Screen width/height:1600/1200 03:50:41 INFO - PROCESS | 6056 | colorDepth:24 03:50:41 INFO - PROCESS | 6056 | Browser inner width/height: 1024/697 03:50:41 INFO - PROCESS | 6056 | __metrics 03:50:42 INFO - PROCESS | 6056 | 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" 03:50:42 INFO - PROCESS | 6056 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:50:43 INFO - PROCESS | 6056 | console.error: PushService: 03:50:43 INFO - PROCESS | 6056 | stateChangeProcessEnqueue: Error transitioning state 03:50:43 INFO - PROCESS | 6056 | UnknownError 03:50:43 INFO - TEST-INFO | 6056: exit 0 03:50:43 INFO - Browser initialized. 03:50:43 INFO - Running cycle 1/1 for dromaeo_css test... 03:50:43 INFO - TEST-INFO | started process 6149 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9A7q6W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:50:55 INFO - PROCESS | 6149 | RSS: Main: 141357056 03:50:55 INFO - PROCESS | 6149 | 03:53:57 INFO - PROCESS | 6149 | Cycle 1(1): loaded http://localhost:51329/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51329/tests/dromaeo/cssquery-ext.html) 03:53:59 INFO - PROCESS | 6149 | RSS: Main: 279199744 03:53:59 INFO - PROCESS | 6149 | 03:57:01 INFO - PROCESS | 6149 | Cycle 1(1): loaded http://localhost:51329/tests/dromaeo/cssquery-ext.html (next: http://localhost:51329/tests/dromaeo/cssquery-jquery.html) 03:57:02 INFO - PROCESS | 6149 | RSS: Main: 223576064 03:57:02 INFO - PROCESS | 6149 | 04:00:04 INFO - PROCESS | 6149 | Cycle 1(1): loaded http://localhost:51329/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51329/tests/dromaeo/cssquery-mootools.html) 04:00:04 INFO - PROCESS | 6149 | RSS: Main: 176484352 04:00:04 INFO - PROCESS | 6149 | 04:03:06 INFO - PROCESS | 6149 | Cycle 1(1): loaded http://localhost:51329/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51329/tests/dromaeo/cssquery-prototype.html) 04:03:07 INFO - PROCESS | 6149 | RSS: Main: 173551616 04:03:07 INFO - PROCESS | 6149 | 04:06:09 INFO - PROCESS | 6149 | Cycle 1(1): loaded http://localhost:51329/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51329/tests/dromaeo/cssquery-yui.html) 04:06:10 INFO - PROCESS | 6149 | RSS: Main: 173801472 04:06:10 INFO - PROCESS | 6149 | 04:09:13 INFO - PROCESS | 6149 | Cycle 1(1): loaded http://localhost:51329/tests/dromaeo/cssquery-yui.html (next: http://localhost:51329/tests/dromaeo/cssquery-dojo.html) 04:09:13 INFO - PROCESS | 6149 | RSS: Main: 240922624 04:09:13 INFO - PROCESS | 6149 | 04:09:13 INFO - PROCESS | 6149 | __start_tp_report 04:09:13 INFO - PROCESS | 6149 | _x_x_mozilla_page_load 04:09:13 INFO - PROCESS | 6149 | _x_x_mozilla_page_load_details 04:09:13 INFO - PROCESS | 6149 | |i|pagename|runs| 04:09:13 INFO - PROCESS | 6149 | |0;dojo.html;3388.949165762513;3421;3458.775179613325;3471.1669199392154;3505.333986542557;5798.130280457934;5841.707914604274;5843.1527428522895;5847.79532716355;5850.034744267202;5849.327327357355;5870.383609720971;5878.706064696769;5888.646681199131;5895.145203945436;5276.050310944029;5298.470152984709;5299.735013249341;5333.986053110084;5417.160340147283;5192.480751924815;5214.296070030536;5396.271503347057;5404.7838086476495;5407.432219616942;5214.061468935609;5270.710110943896;5279.313689220415;5299.152135658295;5305.708186049766;5368.0605893968395;5371.81198658049;5382.138857782736;5384.326959130109;5386.030514507387;5117.82087626935;5250.921236181461;5291.947080529184;5360.59795515334;5366.060939335601;5630.014747419185;5717.857053573653;5727.427257274281;5824.767009319622;5825.854353641193;6224.782132625336;6237.896340400951;6253.093301471306;6259.812205633838;6266.780662676784;6279.497640188774;6284.654344011124;6288.213973253343;6295.338989406138;6316.178896743394;5647.407022262644;5697.1169468732;5782;5853.326867410217;5856.355800861902;6439.613623182624;6444.613323200623;6445.452136568444;6463.57986730861;6465.806025819233;6122.836661034389;6184.257889053342;6193.1329613854095;6194.5044396448175;6198.256209254918;6087.482563982023;6124.326324104345;6168.845778855564;6230.59501132425;6293.213348331458;6239.906401403982;6249.125122482856;6262.5929314594405;6269.65516896566;6275.843103922439;6041.305249896231;6046.879062418727;6061.605995610271;6125.459783291287;6169;4772.570468657837;4816.20532612115;4830;4836.371271734652;4853.927191092136;4190.643795277375;4285.37862010013;4296.334068219431;4299.699021068495;4339.5443478434945;5822.097574875901;5866.794662186803;5883;5964.433378829004;6012.248468941382;6252.956436442168;6298.590591611531;6305.117283580303;6307.842303942346;6309.274433440121;5952.821415357546;5956.13636022783;5957.434043765922;5962.910556341659;6003.759849605967;5143.485651434827;5185.351831021122;5195.506426889439;5224.451432599522;5262.868428289246;6501.2523559791225;6538.934610653928;6539.803805885831;6546.0508226307875;6553.967230163914;6189;6232.314445411092;6245.937540624535;6246.812595622138;6264.937350626436;6203.379662033941;6282.057691346335;6355.491560675043;6425.164728585441;6472.902906456313;6203.596766210182;6203.720832562636;6206.317305096345;6211.689415529115;6211.9068213977735;6307.05394190875;6314.210723659543;6316.11574379578;6316.778912738032;6320.936790632035;5862.648241105548;5956.791512297049;6039.094135879654;6127;6133.325334213144;6109.725062372293;6110.052941794029;6116.974953844038;6122.794608433986;6126.151269885628;6065.363136870565;6092.939070609237;6094.177286066324;6095.451409373178;6124.785632503019;5918.73365698536;5958.612690175125;5978.910316345346;6038.7282572283475;6063.969680151571;5935.020721581063;5937.020391635505;5964.731587078505;5972.701364931823;5978.4021597839865 04:09:13 INFO - PROCESS | 6149 | |1;ext.html;786.563457281209;847.593155285463;866.5017614871449;936.9484678342693;949.2927768812236;31173.220669483246;42855.35716964244;43473.565264347344;43487;43606.34590481142;45201;45230.77384613072;45248.77375613125;45529.317060244124;45535.316970245476;7875.409344299172;9191.81616367674;9319.627214911396;9322.720318390426;9329.95335023324;7875.251851074138;10426.426546539937;10621.681349559525;10683.572657093708;10686.091682207021;10754.623588174416;10800.891991080107;10803.243772935897;10809;10809.081228095622;10722.517486713117;10727.892721072767;10744.409057501834;10753.731156721145;10779.78440431191;10606.098481629071;10615.469226538642;10644.680659580225;10673.146148308195;10691.518881650345;43027.35458968118;43254.35118473225;43260.783696081315;43278.35082473765;43363.56636433627;4248.299030659938;4289.627010607029;4330.610245077926;4392.516823149451;4482.282834746425;30011.849940750373;30418.54372184419;30738.692613073807;30896.691033089606;31712;4329.740215587043;4354.934675979863;4361.694681372305;4362.411074504965;4447.310666846644;233729;235144;235253;235381;235419;54545;54880.72559637176;54953;54955;55032.17451738147;56381;56845;56943.86808725477;56964.43035569674;56984.71507642518;16018.519444416686;16187.676246475005;16249;16356.182190890646;16361.263743131587;12250.81623775644;12423.937880310541;14250.144991300554;14265.928670356581;14299.714005719827;12832.101752877395;13146.21122732639;13517.66205844842;13524.391402386917;13544.119632223874;5569.610127291051;5654.123610840326;5675.801346952837;5685.943140568541;5793;1019.8623185869961;1032.3547782635853;1038.2939601071344;1049.984250236232;1057.603398725478;28385.858070709928;28494.572581411296;28629.565874966567;28902.132936011953;28911.710882891322;1093.327603523827;1109.4840898982013;1110.6890070780194;1113;1127.3179726265655;14534.781978270334;15183.620409489851;15283.923580382027;15455.768163477556;15513.37946482128;297071;310610;310764;311010;311671;56030.71984640132;56221.43778562244;56263.71868140633;56331;56485.15272270837;1422.6597247527966;1471.5070451398688;1475.0928179169948;1476.247113971882;1489.0916540910252;27060.864695676395;28571;28674.856625716737;28780.136595902157;28827.7117228825;35507.28985420225;35676.28647427089;35681.64318356783;35722;35823.28353432969;289815;300863;344025;344175;346121;24932;25241.24276271715;28638.284042899093;30161.245968850955;30413;38136.809315953244;38496.422553661156;38618.42072368861;38790.4181437273;38875.80562097171;30838;31271.84364078256;31447.52828707617;31476.370472589962;31636.209094771893;30406.087817365515;30898.382032359677;31045.534316984813;31127.533087004165;31648.367032658756;34832;34886.30227395488;35233.29533409369;35440.29119417649;35569.28861422809;28685.856570717013;29061.564076539293;29162.854185728935;29374;29654.851725741235;23252.41868953222;24072.157474488307;24274.878625606758;24298.14956476515;24543.14099006526 04:09:13 INFO - PROCESS | 6149 | |2;jquery.html;458.2942268905881;462.5443937721346;479.5755756155802;481.4920259126622;494.0588179518018;19909.203631854725;21249.787502124993;21584.784152158514;21805.127794888205;22011.779882201172;22611;22846.200382986597;22907.198248061322;23190;23203.18788842391;6765.22199947005;6771;6872.175338959307;6932.653367331638;6939.791806245821;6926.307369263084;6941.965290173542;7262.52793568419;7535.20880307568;7545.434092443062;7437.776866693981;7701.037370328709;7702.998610180669;7709;7713.5757533335645;7563.546187228756;7754.340881025119;7789.883151752728;7805.4145939054515;7807.570583617899;7385.778426647209;7540.019797426355;7581.431392645519;7596.696132154707;7599.202083781179;25083.87458062698;25407.364815879566;25501.107461238953;25546.872265638554;25607;3154.7160755532113;3228.677132286776;3284.6715328467203;3288.901332960015;3328.0348698877297;16664.916675416545;16851.831481685273;16983.405580804614;17049.318027278892;17620.647587048188;2354.693889794316;2444.7799698027084;2534.9873250633814;2545.185540627;2613.568761154407;87822;103415.96584034139;112085;113445.86554134518;115528;18441.170147343135;18533.53666158357;18764.81235187658;18884.716729249074;19019.80980190208;32122;32132.678673213435;32291.677083229337;32448.513272300934;32601.18497037593;9933.155681766986;10054.145397641136;10211.591536338612;10441.425721585234;10485.318454300446;8430.114837941928;8450.19723126302;8480;8660.09069047741;8660.913390866137;9280.350375473652;9449.76375590602;9465;9556.331056826095;9611.807763844685;3732.8880133596035;3798.867039653599;3838.980805095957;3920.6079392060565;3984.7210695251633;944.1125342178331;953.585190442146;963.4412041016194;971.0386717150112;975.1223898491414;19810.306639267557;21399.89300053521;21569.67645485319;21830.126794928026;21990.340289791333;943.7168849345306;953.1231267234161;964.2767924056957;968.2399316536485;977.0717818072859;11345.376004319676;11911.761764764657;11958.521659133712;12056.87943120575;12183.208091474025;655004;662328;663489;684330;685117;110916;112128.4393578027;113395;114359;116753.41623291999;1271.8028705550655;1283.3006011723633;1301.4143635364314;1302.4204229117952;1308.6205000549735;18000.729989050436;18166.182521786286;18512.907435462737;18556.814431856048;19117.330893418664;34700.47949280712;47954.040919182116;49105;49135.75432122817;50089;60501.69749151226;61489.385106148366;63309;64609;65058;9893.35693179941;11235;11682.12384071181;12956.93521532424;13027.934860326017;14464.927675361976;14490.34793434277;14628.707425851637;14678.119312841263;14772.261386930826;12732.554360597334;12845.715428457079;12880.227186368847;12911.677208069497;12940.676483087998;11215.214934954474;11218.943905280421;11267.830982535104;11287.548498059985;11366.772664546497;12559.434825432694;12629.2422454653;12879.488499742834;13080.476780928657;13613.523526676914;12003.879961200626;12139;12214.022878169902;12221.388930553614;12230.816537752118;12045.096617753528;12083.214591051554;12273.570425035081;12472.251664900135;12544.121911466109 04:09:13 INFO - PROCESS | 6149 | |3;mootools.html;1422.3101795629125;1540.3838464614157;1570.654456019676;1571.8742500599953;1583.1134564643794;9669.274804389672;11415.942920285397;11778.293302401851;12157.39213039349;12190.329531875743;11796.11529135314;11906.702332441693;11909.821352679695;11941.164118511708;11947.283163010205;2027.0675489274952;2084.937451876446;2418.7218469875925;2461.8030557555444;2462.187478132221;2473.4311108445067;2542.3135753346587;2552.744725527451;2552.7574880386333;2552.7702506774385;2387.6418537219383;2412.8431651942656;2423.236680445663;2423.3941514621347;2427.0413186791206;2439.0731522021697;2457.8402403843693;2462.642916777066;2470.4441500662388;2470.851748895063;2351.929442116722;2352.882355882199;2355.5406695694346;2359.2214569192297;2393.210240620591;11712.238704484182;11799.882001179963;11804.822927656092;11814.527418903233;11824.527018919232;5760.481556659879;5906.409359064102;5958.374370691058;6045.123457098696;6082.96958515209;3410.7441941854213;3548.7515873888838;3628.4738712886483;3708.87018954338;3711.5482601180893;1354.878060974517;1373.1212024304452;1395.4694622604725;1423.5515812519022;1469.919154446505;16327.83672163287;17678.646427071515;17703.38038168658;17721.822781772145;17739.911300443546;4760.619150467955;4818.542238487338;4910.165271903785;4936.259561065869;4937.308776771255;13010.284434356201;13118.803217951738;13132.474701012043;13136.868631313755;13139.080264381402;6117.235345581802;6117.541184411187;6119.694015299217;6121.112438696364;6131.478824299896;4557.225271703752;5088.92366614501;5669.659820410789;5697.658140511582;5732.914006289909;4137.97931010343;4141.233871733739;4179.101493178981;4229.2175947449205;4234.153169366139;1094.0536435982817;1094.3105843318817;1099.7305660113163;1113.059464752283;1114.0670935926162;907.3875134284332;930.2046750028763;935.6257497001254;940.1632547033146;949.9097585729345;7488.176300606929;7504.099508059068;7504.662290196954;7536.773896783106;7681.193474685189;1051.4427353502656;1052.3370276725614;1058.364981011387;1059.7403636109202;1103.2938919091787;4790.209615413488;4831.51684831514;4923.409190897115;5073.086844368049;5078.390593128848;14181.220032898289;14299.57101286963;15081.47214847497;15098.54704358871;15237.3905043797;14361.712765744836;14531.92734036323;14846.851531484763;14892.329845156994;14909;10825.945870270598;10999.670009899715;11017.394043327695;11017.889821101846;11033.117350612092;7142.250063743232;7284.781456556312;7637.159912409519;7655.6937722490475;7776.105747839071;13052.934735326262;13189.40647670838;13215.207087574776;13219.867801321863;13249.933750331187;13258.602241932758;13386.062975592005;13509.324533773075;13606.727865442437;13668.93165534166;10081.949590252001;10160.441175735405;10176.592936282466;10258.640947566799;10282.845757313797;11062.502187401426;11127.165462590176;11167.832482512931;11198.21612487118;11331.490082946122;9469.479178644966;9517.714468565953;9621.56702948355;9671.274654401088;9915.058069483388;9096.454212747256;9116.817663646823;9133.588988495401;9164.587593558439;9310.581023854078;8615.483071015758;8768.342374321825;8768.956155219183;8777.42946708462;8779.517126557845;5996.910046349221;6039.758409663565;6130.2030736004035;6152.230971128609;6237.064440333805;6018.759249630141;6192.566520343533;6201;6314.084457753692;6314.715837787218 04:09:13 INFO - PROCESS | 6149 | |4;prototype.html;416.843683618643;425.9212045771533;439.936209249659;446.851591410076;449.9640028797696;5120.718360490171;5324.376065300082;5382.192671099337;5382.74344021867;5400.270963419937;5185.274061631365;5273.604479664022;5280.947190528093;5289.100852855015;5294.549963253118;4359.629431498326;4445.733256004634;4454.799534020961;4480.843170489017;4494.752788596617;4450.4881938577155;4455.309427038814;4466.888327791815;4470.307102399133;4475.351074094255;4465.084657645175;4466.486354069275;4469.150861336336;4470.530594287602;4501.414816073906;4374.387585737999;4412.205802955466;4441.244988351988;4480.081583275437;4519.52544982777;4143.316352801784;4157.688173386978;4165.354370072643;4190.55999120091;4198.8110535026;5173.32746742921;5185.118529849935;5212.400573934008;5215.295935048758;5228.137357336035;4919;5140.820071297487;5157.200633901752;5162.5095615916425;5242.711650859202;5513.917291240595;5563.887222555505;5575.972120139373;5578.944210557883;5597.720113994284;4663.696859704109;4670.416197975253;4704.388429504143;4716.198246298138;4746;5545.77816887324;5819.330776960619;5867.242396073839;5912.6156799808305;5919.437653422875;4787.976060119677;4805.399325084364;4831.758412079382;4833.685810422311;4885.316055752198;4955.578775804025;4957.578605818546;4959.90080198394;4962.851114466572;4965.237340744056;5002.349694539686;5155.432902380735;5182.689038657692;5204.869878253074;5233.932596624312;5562.860928476821;5566.248556444909;5572.414896435897;5583.274174357308;5593.524550413179;1635.331961399366;1660.5682522544225;1676.2708221923501;1688.9493315200564;1696.2366934879353;1248.6242924051005;1298.8701129887124;1310.9184922439026;1312.4553310376277;1319.7624427603123;5353.57171426285;5376.763344430803;5399.515133338285;5414.187871819258;5456.945430545644;5643.858903527444;5649.4633009864;5706.4864162225595;5723.828285151369;5731.3379120054715;5097.821576244888;5407.187932507652;5451.781928722886;5480;5506.889862202734;3008.247938015496;3008.7964814074107;3016.3967206558336;3020.9395812083635;3021.56187352837;5729.684867332254;5893.793717219876;5901.29184497863;5922.615030023035;5937.33754548727;5520.889582208413;5731.369549349568;5792.304923409218;5807.5644326675665;5813.82558523245;5726.971365143231;5841.795537156264;5871.970640146943;5892;5926.407359264125;5691.971540142273;5696.401877802937;5699.373068962328;5728.427157284238;5729.942700572941;5278.340207474066;5426.647267927572;5557.44425557441;5586.469285417878;5610.803621873215;5869.325027621878;5872.55955803325;5884.852878678067;5899.616524925866;5915;4571.405717256735;4730.385049943486;4739.743967848592;4750.928736068892;4794.208955522439;5663.546916246608;5705.429457054262;5705.486506214461;5710.5717071219;5713.285839270091;5562.415946325739;5565.944340556543;5580.218769372373;5637.830865074055;5652.943470565407;5313.2561441397465;5592.328920529562;5605.1872478491205;5616.747246373841;5646.971765141148;5594.580406469449;5596.804111856066;5599.580031497572;5604;5632.85917852057;5385.38068122171;5664.065429204135;5665.461781130786;5676.375598684224;5690.041178587194;5359.705216212989;5388.949154914911;5469.507744303032;5481.561475082063;5510.669359838423 04:09:13 INFO - PROCESS | 6149 | |5;yui.html;839.8278352937648;843.7763992541974;889.8487257166281;899.105390136814;902.5532361481064;17473.825261747377;18977.146028428753;18996.145173467194;19038.61922761544;19049.61900761984;17237.82762172378;17398.47804565862;17416.129193540302;17423.564410889736;17455;905.5245995852181;936.148105224246;943.556528431636;947.673052796784;948.7770373962148;925.1396201532572;925.6713866577369;926.4533924984258;926.735880274122;926.879505664266;993.920486361093;994.2990191914682;995.343073571443;996.3573495095659;996.4419924842075;985.8816941966938;989.6387818446251;989.6931951095147;989.7624570103123;990.0347161517535;722.8951801988683;725.7459889038847;729.8868675355328;729.956202627844;730.2916171313817;18961.91836367819;19127.13927873249;19411.22355105794;19504;19864.503387415287;17646.558836029075;17704.291828326852;17739.73390399145;17757.911210443996;17960;13581.253031083286;13643.454261829515;13668.52160174389;13689.86310136896;13770.173789572658;4196.181744559812;4210.431591735138;4244.681648876316;4250.149970006011;4345.630621397185;53068.46931530674;55208.44791552114;76778.61610691967;86846.39460816186;96051;13684.384202710902;13946.232957187249;13979.370928308048;14009.369578368998;14319.284035798168;26379.736202638112;26459.867700661758;26673.866630666722;26754.732452675613;27009.054683085997;9566.330356874952;9639.228861691048;9788.168005719594;9862.950685246671;9874.950625246827;5086.701751942466;5238.397584277857;5256.894862102813;5316.388615309212;5323.441038690959;3836.4245363195746;3932.8230229639735;3947.684185265172;3958.5645578986287;4008.018035581243;648.6540428610651;649.250116115887;655.1712084213474;664.7540410048313;665.2449469851656;800.8358286551243;817.2399668308411;832.0930186097183;849.9107593702696;856.9485830850169;10990.340579565092;11533.423328833525;11598.304101753922;11745.530178792753;11931.880681193077;817.587118505163;830.543201239316;838.8867502887155;851.1063383447356;856.5674334461057;5193.896122077537;5499.279288235018;5561.888762224733;5664.943350566524;5686.545076393879;83855.58072209722;87128.56435717907;87441;88777.55611221903;90886.54556727174;43376;50062;58898.70550647219;74595;79463.60268198622;61332.69333653303;69398;69709;70741.64629176924;73900.63049684717;11786.233894796811;12900.096993210385;12994.740105198032;13012.479500819862;13084.934575327063;47391;48032.759836200596;48060.519394805604;48585;48716.512834872614;54487.455125448236;54738;55453.722731386086;55647.72176139093;57360.42639573551;13960.2321872298;14131.434742610181;14866.85133148698;15046.097234165985;16110;14662.63343416423;15105.622359441102;16199.109049002418;17502.299908003537;17617.82382176162;15828.68342633164;15892;16092.436764713178;16145;17389.21748521345;14608.926955365156;14662.486812961495;14924.253787310374;15163.696726065196;16292.42976495817;15286.541403757905;15558.766618500958;15709.607259818597;15823.129727865078;16630.168491575616;10415.791684166426;10601.946990265;10694.946525267324;10732.14142868553;11358.886411136113;12502.687432814253;12886.548970786353;13044.934775326063;13186.868131318564;13451.125676831367 04:09:13 INFO - PROCESS | 6149 | __end_tp_report 04:09:13 INFO - PROCESS | 6149 | __start_cc_report 04:09:13 INFO - PROCESS | 6149 | _x_x_mozilla_cycle_collect,1490 04:09:13 INFO - PROCESS | 6149 | __end_cc_report 04:09:13 INFO - PROCESS | 6149 | __startTimestamp1496056153420__endTimestamp 04:09:13 INFO - PROCESS | 6149 | 04:09:13 INFO - PROCESS | 6149 | ------- Summary: start ------- 04:09:13 INFO - PROCESS | 6149 | Number of tests: 6 04:09:13 INFO - PROCESS | 6149 | 04:09:13 INFO - PROCESS | 6149 | [#0] dojo.html Cycles:165 Average:5795.67 Median:5978.91 stddev:651.32 (10.9%) stddev-sans-first:625.35 04:09:13 INFO - PROCESS | 6149 | Values: 3388.9 3421.0 3458.8 3471.2 3505.3 5798.1 5841.7 5843.2 5847.8 5850.0 5849.3 5870.4 5878.7 5888.6 5895.1 5276.1 5298.5 5299.7 5334.0 5417.2 5192.5 5214.3 5396.3 5404.8 5407.4 5214.1 5270.7 5279.3 5299.2 5305.7 5368.1 5371.8 5382.1 5384.3 5386.0 5117.8 5250.9 5291.9 5360.6 5366.1 5630.0 5717.9 5727.4 5824.8 5825.9 6224.8 6237.9 6253.1 6259.8 6266.8 6279.5 6284.7 6288.2 6295.3 6316.2 5647.4 5697.1 5782.0 5853.3 5856.4 6439.6 6444.6 6445.5 6463.6 6465.8 6122.8 6184.3 6193.1 6194.5 6198.3 6087.5 6124.3 6168.8 6230.6 6293.2 6239.9 6249.1 6262.6 6269.7 6275.8 6041.3 6046.9 6061.6 6125.5 6169.0 4772.6 4816.2 4830.0 4836.4 4853.9 4190.6 4285.4 4296.3 4299.7 4339.5 5822.1 5866.8 5883.0 5964.4 6012.2 6253.0 6298.6 6305.1 6307.8 6309.3 5952.8 5956.1 5957.4 5962.9 6003.8 5143.5 5185.4 5195.5 5224.5 5262.9 6501.3 6538.9 6539.8 6546.1 6554.0 6189.0 6232.3 6245.9 6246.8 6264.9 6203.4 6282.1 6355.5 6425.2 6472.9 6203.6 6203.7 6206.3 6211.7 6211.9 6307.1 6314.2 6316.1 6316.8 6320.9 5862.6 5956.8 6039.1 6127.0 6133.3 6109.7 6110.1 6117.0 6122.8 6126.2 6065.4 6092.9 6094.2 6095.5 6124.8 5918.7 5958.6 5978.9 6038.7 6064.0 5935.0 5937.0 5964.7 5972.7 5978.4 04:09:13 INFO - PROCESS | 6149 | 04:09:13 INFO - PROCESS | 6149 | [#1] ext.html Cycles:180 Average:45412.11 Median:30788.35 stddev:76490.67 (248.4%) stddev-sans-first:76631.86 04:09:13 INFO - PROCESS | 6149 | Values: 786.6 847.6 866.5 936.9 949.3 31173.2 42855.4 43473.6 43487.0 43606.3 45201.0 45230.8 45248.8 45529.3 45535.3 7875.4 9191.8 9319.6 9322.7 9330.0 7875.3 10426.4 10621.7 10683.6 10686.1 10754.6 10800.9 10803.2 10809.0 10809.1 10722.5 10727.9 10744.4 10753.7 10779.8 10606.1 10615.5 10644.7 10673.1 10691.5 43027.4 43254.4 43260.8 43278.4 43363.6 4248.3 4289.6 4330.6 4392.5 4482.3 30011.8 30418.5 30738.7 30896.7 31712.0 4329.7 4354.9 4361.7 4362.4 4447.3 233729.0 235144.0 235253.0 235381.0 235419.0 54545.0 54880.7 54953.0 54955.0 55032.2 56381.0 56845.0 56943.9 56964.4 56984.7 16018.5 16187.7 16249.0 16356.2 16361.3 12250.8 12423.9 14250.1 14265.9 14299.7 12832.1 13146.2 13517.7 13524.4 13544.1 5569.6 5654.1 5675.8 5685.9 5793.0 1019.9 1032.4 1038.3 1050.0 1057.6 28385.9 28494.6 28629.6 28902.1 28911.7 1093.3 1109.5 1110.7 1113.0 1127.3 14534.8 15183.6 15283.9 15455.8 15513.4 297071.0 310610.0 310764.0 311010.0 311671.0 56030.7 56221.4 56263.7 56331.0 56485.2 1422.7 1471.5 1475.1 1476.2 1489.1 27060.9 28571.0 28674.9 28780.1 28827.7 35507.3 35676.3 35681.6 35722.0 35823.3 289815.0 300863.0 344025.0 344175.0 346121.0 24932.0 25241.2 28638.3 30161.2 30413.0 38136.8 38496.4 38618.4 38790.4 38875.8 30838.0 31271.8 31447.5 31476.4 31636.2 30406.1 30898.4 31045.5 31127.5 31648.4 34832.0 34886.3 35233.3 35440.3 35569.3 28685.9 29061.6 29162.9 29374.0 29654.9 23252.4 24072.2 24274.9 24298.1 24543.1 04:09:13 INFO - PROCESS | 6149 | 04:09:13 INFO - PROCESS | 6149 | [#2] jquery.html Cycles:180 Average:37642.79 Median:13971.18 stddev:110169.92 (788.6%) stddev-sans-first:110443.59 04:09:13 INFO - PROCESS | 6149 | Values: 458.3 462.5 479.6 481.5 494.1 19909.2 21249.8 21584.8 21805.1 22011.8 22611.0 22846.2 22907.2 23190.0 23203.2 6765.2 6771.0 6872.2 6932.7 6939.8 6926.3 6942.0 7262.5 7535.2 7545.4 7437.8 7701.0 7703.0 7709.0 7713.6 7563.5 7754.3 7789.9 7805.4 7807.6 7385.8 7540.0 7581.4 7596.7 7599.2 25083.9 25407.4 25501.1 25546.9 25607.0 3154.7 3228.7 3284.7 3288.9 3328.0 16664.9 16851.8 16983.4 17049.3 17620.6 2354.7 2444.8 2535.0 2545.2 2613.6 87822.0 103416.0 112085.0 113445.9 115528.0 18441.2 18533.5 18764.8 18884.7 19019.8 32122.0 32132.7 32291.7 32448.5 32601.2 9933.2 10054.1 10211.6 10441.4 10485.3 8430.1 8450.2 8480.0 8660.1 8660.9 9280.4 9449.8 9465.0 9556.3 9611.8 3732.9 3798.9 3839.0 3920.6 3984.7 944.1 953.6 963.4 971.0 975.1 19810.3 21399.9 21569.7 21830.1 21990.3 943.7 953.1 964.3 968.2 977.1 11345.4 11911.8 11958.5 12056.9 12183.2 655004.0 662328.0 663489.0 684330.0 685117.0 110916.0 112128.4 113395.0 114359.0 116753.4 1271.8 1283.3 1301.4 1302.4 1308.6 18000.7 18166.2 18512.9 18556.8 19117.3 34700.5 47954.0 49105.0 49135.8 50089.0 60501.7 61489.4 63309.0 64609.0 65058.0 9893.4 11235.0 11682.1 12956.9 13027.9 14464.9 14490.3 14628.7 14678.1 14772.3 12732.6 12845.7 12880.2 12911.7 12940.7 11215.2 11218.9 11267.8 11287.5 11366.8 12559.4 12629.2 12879.5 13080.5 13613.5 12003.9 12139.0 12214.0 12221.4 12230.8 12045.1 12083.2 12273.6 12472.3 12544.1 04:09:13 INFO - PROCESS | 6149 | 04:09:13 INFO - PROCESS | 6149 | [#3] mootools.html Cycles:180 Average:7283.74 Median:2425.22 stddev:4662.72 (192.3%) stddev-sans-first:4655.00 04:09:13 INFO - PROCESS | 6149 | Values: 1422.3 1540.4 1570.7 1571.9 1583.1 9669.3 11415.9 11778.3 12157.4 12190.3 11796.1 11906.7 11909.8 11941.2 11947.3 2027.1 2084.9 2418.7 2461.8 2462.2 2473.4 2542.3 2552.7 2552.8 2552.8 2387.6 2412.8 2423.2 2423.4 2427.0 2439.1 2457.8 2462.6 2470.4 2470.9 2351.9 2352.9 2355.5 2359.2 2393.2 11712.2 11799.9 11804.8 11814.5 11824.5 5760.5 5906.4 5958.4 6045.1 6083.0 3410.7 3548.8 3628.5 3708.9 3711.5 1354.9 1373.1 1395.5 1423.6 1469.9 16327.8 17678.6 17703.4 17721.8 17739.9 4760.6 4818.5 4910.2 4936.3 4937.3 13010.3 13118.8 13132.5 13136.9 13139.1 6117.2 6117.5 6119.7 6121.1 6131.5 4557.2 5088.9 5669.7 5697.7 5732.9 4138.0 4141.2 4179.1 4229.2 4234.2 1094.1 1094.3 1099.7 1113.1 1114.1 907.4 930.2 935.6 940.2 949.9 7488.2 7504.1 7504.7 7536.8 7681.2 1051.4 1052.3 1058.4 1059.7 1103.3 4790.2 4831.5 4923.4 5073.1 5078.4 14181.2 14299.6 15081.5 15098.5 15237.4 14361.7 14531.9 14846.9 14892.3 14909.0 10825.9 10999.7 11017.4 11017.9 11033.1 7142.3 7284.8 7637.2 7655.7 7776.1 13052.9 13189.4 13215.2 13219.9 13249.9 13258.6 13386.1 13509.3 13606.7 13668.9 10081.9 10160.4 10176.6 10258.6 10282.8 11062.5 11127.2 11167.8 11198.2 11331.5 9469.5 9517.7 9621.6 9671.3 9915.1 9096.5 9116.8 9133.6 9164.6 9310.6 8615.5 8768.3 8769.0 8777.4 8779.5 5996.9 6039.8 6130.2 6152.2 6237.1 6018.8 6192.6 6201.0 6314.1 6314.7 04:09:13 INFO - PROCESS | 6149 | 04:09:13 INFO - PROCESS | 6149 | [#4] prototype.html Cycles:180 Average:4866.76 Median:5356.64 stddev:1285.54 (24.0%) stddev-sans-first:1245.00 04:09:13 INFO - PROCESS | 6149 | Values: 416.8 425.9 439.9 446.9 450.0 5120.7 5324.4 5382.2 5382.7 5400.3 5185.3 5273.6 5280.9 5289.1 5294.5 4359.6 4445.7 4454.8 4480.8 4494.8 4450.5 4455.3 4466.9 4470.3 4475.4 4465.1 4466.5 4469.2 4470.5 4501.4 4374.4 4412.2 4441.2 4480.1 4519.5 4143.3 4157.7 4165.4 4190.6 4198.8 5173.3 5185.1 5212.4 5215.3 5228.1 4919.0 5140.8 5157.2 5162.5 5242.7 5513.9 5563.9 5576.0 5578.9 5597.7 4663.7 4670.4 4704.4 4716.2 4746.0 5545.8 5819.3 5867.2 5912.6 5919.4 4788.0 4805.4 4831.8 4833.7 4885.3 4955.6 4957.6 4959.9 4962.9 4965.2 5002.3 5155.4 5182.7 5204.9 5233.9 5562.9 5566.2 5572.4 5583.3 5593.5 1635.3 1660.6 1676.3 1688.9 1696.2 1248.6 1298.9 1310.9 1312.5 1319.8 5353.6 5376.8 5399.5 5414.2 5456.9 5643.9 5649.5 5706.5 5723.8 5731.3 5097.8 5407.2 5451.8 5480.0 5506.9 3008.2 3008.8 3016.4 3020.9 3021.6 5729.7 5893.8 5901.3 5922.6 5937.3 5520.9 5731.4 5792.3 5807.6 5813.8 5727.0 5841.8 5872.0 5892.0 5926.4 5692.0 5696.4 5699.4 5728.4 5729.9 5278.3 5426.6 5557.4 5586.5 5610.8 5869.3 5872.6 5884.9 5899.6 5915.0 4571.4 4730.4 4739.7 4750.9 4794.2 5663.5 5705.4 5705.5 5710.6 5713.3 5562.4 5565.9 5580.2 5637.8 5652.9 5313.3 5592.3 5605.2 5616.7 5647.0 5594.6 5596.8 5599.6 5604.0 5632.9 5385.4 5664.1 5665.5 5676.4 5690.0 5359.7 5388.9 5469.5 5481.6 5510.7 04:09:13 INFO - PROCESS | 6149 | 04:09:13 INFO - PROCESS | 6149 | [#5] yui.html Cycles:180 Average:19043.01 Median:25868.32 stddev:22980.64 (88.8%) stddev-sans-first:23004.45 04:09:13 INFO - PROCESS | 6149 | Values: 839.8 843.8 889.8 899.1 902.6 17473.8 18977.1 18996.1 19038.6 19049.6 17237.8 17398.5 17416.1 17423.6 17455.0 905.5 936.1 943.6 947.7 948.8 925.1 925.7 926.5 926.7 926.9 993.9 994.3 995.3 996.4 996.4 985.9 989.6 989.7 989.8 990.0 722.9 725.7 729.9 730.0 730.3 18961.9 19127.1 19411.2 19504.0 19864.5 17646.6 17704.3 17739.7 17757.9 17960.0 13581.3 13643.5 13668.5 13689.9 13770.2 4196.2 4210.4 4244.7 4250.1 4345.6 53068.5 55208.4 76778.6 86846.4 96051.0 13684.4 13946.2 13979.4 14009.4 14319.3 26379.7 26459.9 26673.9 26754.7 27009.1 9566.3 9639.2 9788.2 9863.0 9875.0 5086.7 5238.4 5256.9 5316.4 5323.4 3836.4 3932.8 3947.7 3958.6 4008.0 648.7 649.3 655.2 664.8 665.2 800.8 817.2 832.1 849.9 856.9 10990.3 11533.4 11598.3 11745.5 11931.9 817.6 830.5 838.9 851.1 856.6 5193.9 5499.3 5561.9 5664.9 5686.5 83855.6 87128.6 87441.0 88777.6 90886.5 43376.0 50062.0 58898.7 74595.0 79463.6 61332.7 69398.0 69709.0 70741.6 73900.6 11786.2 12900.1 12994.7 13012.5 13084.9 47391.0 48032.8 48060.5 48585.0 48716.5 54487.5 54738.0 55453.7 55647.7 57360.4 13960.2 14131.4 14866.9 15046.1 16110.0 14662.6 15105.6 16199.1 17502.3 17617.8 15828.7 15892.0 16092.4 16145.0 17389.2 14608.9 14662.5 14924.3 15163.7 16292.4 15286.5 15558.8 15709.6 15823.1 16630.2 10415.8 10601.9 10694.9 10732.1 11358.9 12502.7 12886.5 13044.9 13186.9 13451.1 04:09:13 INFO - PROCESS | 6149 | -------- Summary: end -------- 04:09:13 INFO - PROCESS | 6149 | 04:09:14 INFO - TEST-INFO | 6149: exit 0 04:09:14 INFO - TEST-OK | dromaeo_css | took 1120126ms 04:09:14 INFO - TEST-START | kraken 04:09:14 INFO - operating with platform_type : linux_ 04:09:14 INFO - Initialising browser for kraken test... 04:09:14 INFO - TEST-INFO | started process 6454 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpca6Fp2/profile http://localhost:51329/getInfo.html) 04:09:18 INFO - PROCESS | 6454 | __metrics Screen width/height:1600/1200 04:09:18 INFO - PROCESS | 6454 | colorDepth:24 04:09:18 INFO - PROCESS | 6454 | Browser inner width/height: 1024/697 04:09:18 INFO - PROCESS | 6454 | __metrics 04:09:20 INFO - PROCESS | 6454 | 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" 04:09:20 INFO - PROCESS | 6454 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:09:21 INFO - TEST-INFO | 6454: exit 0 04:09:21 INFO - Browser initialized. 04:09:21 INFO - Running cycle 1/1 for kraken test... 04:09:21 INFO - TEST-INFO | started process 6551 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpca6Fp2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:09:32 INFO - PROCESS | 6551 | RSS: Main: 141697024 04:09:32 INFO - PROCESS | 6551 | 04:09:35 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:51329/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51329/tests/kraken/driver.html?testName=audio-beat-detection) 04:09:36 INFO - PROCESS | 6551 | RSS: Main: 183783424 04:09:36 INFO - PROCESS | 6551 | 04:09:38 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:51329/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51329/tests/kraken/driver.html?testName=audio-dft) 04:09:39 INFO - PROCESS | 6551 | RSS: Main: 173527040 04:09:39 INFO - PROCESS | 6551 | 04:09:42 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:51329/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51329/tests/kraken/driver.html?testName=audio-fft) 04:09:42 INFO - PROCESS | 6551 | RSS: Main: 265318400 04:09:42 INFO - PROCESS | 6551 | 04:09:44 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:51329/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51329/tests/kraken/driver.html?testName=audio-oscillator) 04:09:45 INFO - PROCESS | 6551 | RSS: Main: 172404736 04:09:45 INFO - PROCESS | 6551 | 04:09:47 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:51329/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51329/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:09:47 INFO - PROCESS | 6551 | RSS: Main: 174002176 04:09:47 INFO - PROCESS | 6551 | 04:09:50 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:51329/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51329/tests/kraken/driver.html?testName=imaging-darkroom) 04:09:51 INFO - PROCESS | 6551 | RSS: Main: 209776640 04:09:51 INFO - PROCESS | 6551 | 04:09:55 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:51329/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51329/tests/kraken/driver.html?testName=imaging-desaturate) 04:09:55 INFO - PROCESS | 6551 | RSS: Main: 206827520 04:09:55 INFO - PROCESS | 6551 | 04:09:58 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:51329/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51329/tests/kraken/driver.html?testName=json-parse-financial) 04:09:59 INFO - PROCESS | 6551 | RSS: Main: 209739776 04:09:59 INFO - PROCESS | 6551 | 04:10:00 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:51329/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51329/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:10:01 INFO - PROCESS | 6551 | RSS: Main: 172670976 04:10:01 INFO - PROCESS | 6551 | 04:10:02 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:51329/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51329/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:10:03 INFO - PROCESS | 6551 | RSS: Main: 179843072 04:10:03 INFO - PROCESS | 6551 | 04:10:05 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:51329/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51329/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:10:06 INFO - PROCESS | 6551 | RSS: Main: 184995840 04:10:06 INFO - PROCESS | 6551 | 04:10:08 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:51329/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51329/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:10:09 INFO - PROCESS | 6551 | RSS: Main: 180748288 04:10:09 INFO - PROCESS | 6551 | 04:10:11 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:51329/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51329/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:10:12 INFO - PROCESS | 6551 | RSS: Main: 184840192 04:10:12 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | Cycle 1(1): loaded http://localhost:51329/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51329/tests/kraken/driver.html?testName=ai-astar) 04:10:14 INFO - PROCESS | 6551 | RSS: Main: 237805568 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | __start_tp_report 04:10:14 INFO - PROCESS | 6551 | _x_x_mozilla_page_load 04:10:14 INFO - PROCESS | 6551 | _x_x_mozilla_page_load_details 04:10:14 INFO - PROCESS | 6551 | |i|pagename|runs| 04:10:14 INFO - PROCESS | 6551 | |0;ai-astar;103;112;113;112;112;113;112;112;112;112 04:10:14 INFO - PROCESS | 6551 | |1;audio-beat-detection;143;113;115;153;115;117;115;117;140;115 04:10:14 INFO - PROCESS | 6551 | |2;audio-dft;161;160;161;160;161;159;160;161;161;160 04:10:14 INFO - PROCESS | 6551 | |3;audio-fft;76;76;75;103;76;76;75;75;96;75 04:10:14 INFO - PROCESS | 6551 | |4;audio-oscillator;82;82;105;82;82;82;94;81;82;105 04:10:14 INFO - PROCESS | 6551 | |5;imaging-gaussian-blur;113;112;113;113;113;113;112;113;113;113 04:10:14 INFO - PROCESS | 6551 | |6;imaging-darkroom;146;146;146;147;147;146;146;147;146;146 04:10:14 INFO - PROCESS | 6551 | |7;imaging-desaturate;93;94;93;93;93;94;93;94;93;94 04:10:14 INFO - PROCESS | 6551 | |8;json-parse-financial;57;55;56;55;55;55;58;56;55;55 04:10:14 INFO - PROCESS | 6551 | |9;json-stringify-tinderbox;52;53;52;54;53;53;54;53;53;52 04:10:14 INFO - PROCESS | 6551 | |10;stanford-crypto-aes;75;84;79;79;81;79;78;84;79;92 04:10:14 INFO - PROCESS | 6551 | |11;stanford-crypto-ccm;127;121;129;121;120;117;138;132;129;130 04:10:14 INFO - PROCESS | 6551 | |12;stanford-crypto-pbkdf2;162;160;165;159;160;160;162;168;163;158 04:10:14 INFO - PROCESS | 6551 | |13;stanford-crypto-sha256-iterative;60;63;60;60;59;59;59;60;66;58 04:10:14 INFO - PROCESS | 6551 | __end_tp_report 04:10:14 INFO - PROCESS | 6551 | __start_cc_report 04:10:14 INFO - PROCESS | 6551 | _x_x_mozilla_cycle_collect,1632 04:10:14 INFO - PROCESS | 6551 | __end_cc_report 04:10:14 INFO - PROCESS | 6551 | __startTimestamp1496056214463__endTimestamp 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | ------- Summary: start ------- 04:10:14 INFO - PROCESS | 6551 | Number of tests: 14 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:2.95 (2.6%) stddev-sans-first:0.44 04:10:14 INFO - PROCESS | 6551 | Values: 103.0 112.0 113.0 112.0 112.0 113.0 112.0 112.0 112.0 112.0 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | [#1] audio-beat-detection Cycles:10 Average:124.30 Median:117.00 stddev:14.91 (12.7%) stddev-sans-first:14.19 04:10:14 INFO - PROCESS | 6551 | Values: 143.0 113.0 115.0 153.0 115.0 117.0 115.0 117.0 140.0 115.0 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.70 (0.4%) stddev-sans-first:0.71 04:10:14 INFO - PROCESS | 6551 | Values: 161.0 160.0 161.0 160.0 161.0 159.0 160.0 161.0 161.0 160.0 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | [#3] audio-fft Cycles:10 Average:80.30 Median:76.00 stddev:10.26 (13.5%) stddev-sans-first:10.77 04:10:14 INFO - PROCESS | 6551 | Values: 76.0 76.0 75.0 103.0 76.0 76.0 75.0 75.0 96.0 75.0 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | [#4] audio-oscillator Cycles:10 Average:87.70 Median:82.00 stddev:9.88 (12.0%) stddev-sans-first:10.26 04:10:14 INFO - PROCESS | 6551 | Values: 82.0 82.0 105.0 82.0 82.0 82.0 94.0 81.0 82.0 105.0 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:113.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 04:10:14 INFO - PROCESS | 6551 | Values: 113.0 112.0 113.0 113.0 113.0 113.0 112.0 113.0 113.0 113.0 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 04:10:14 INFO - PROCESS | 6551 | Values: 146.0 146.0 146.0 147.0 147.0 146.0 146.0 147.0 146.0 146.0 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | [#7] imaging-desaturate Cycles:10 Average:93.40 Median:93.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 04:10:14 INFO - PROCESS | 6551 | Values: 93.0 94.0 93.0 93.0 93.0 94.0 93.0 94.0 93.0 94.0 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:55.50 stddev:1.06 (1.9%) stddev-sans-first:1.01 04:10:14 INFO - PROCESS | 6551 | Values: 57.0 55.0 56.0 55.0 55.0 55.0 58.0 56.0 55.0 55.0 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | [#9] json-stringify-tinderbox Cycles:10 Average:52.90 Median:53.00 stddev:0.74 (1.4%) stddev-sans-first:0.71 04:10:14 INFO - PROCESS | 6551 | Values: 52.0 53.0 52.0 54.0 53.0 53.0 54.0 53.0 53.0 52.0 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:80.00 stddev:4.71 (5.9%) stddev-sans-first:4.47 04:10:14 INFO - PROCESS | 6551 | Values: 75.0 84.0 79.0 79.0 81.0 79.0 78.0 84.0 79.0 92.0 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | [#11] stanford-crypto-ccm Cycles:10 Average:126.40 Median:129.00 stddev:6.50 (5.0%) stddev-sans-first:6.89 04:10:14 INFO - PROCESS | 6551 | Values: 127.0 121.0 129.0 121.0 120.0 117.0 138.0 132.0 129.0 130.0 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.70 Median:162.00 stddev:3.02 (1.9%) stddev-sans-first:3.20 04:10:14 INFO - PROCESS | 6551 | Values: 162.0 160.0 165.0 159.0 160.0 160.0 162.0 168.0 163.0 158.0 04:10:14 INFO - PROCESS | 6551 | 04:10:14 INFO - PROCESS | 6551 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.40 Median:60.00 stddev:2.37 (3.9%) stddev-sans-first:2.51 04:10:14 INFO - PROCESS | 6551 | Values: 60.0 63.0 60.0 60.0 59.0 59.0 59.0 60.0 66.0 58.0 04:10:14 INFO - PROCESS | 6551 | -------- Summary: end -------- 04:10:14 INFO - PROCESS | 6551 | 04:10:15 INFO - TEST-INFO | 6551: exit 0 04:10:15 INFO - TEST-OK | kraken | took 61314ms 04:10:15 INFO - SUITE-END | took 1181s 04:10:15 INFO - Completed test suite (00:19:41) 04:10:15 INFO - javascript benchmark 04:10:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7958.380208825899, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3388.949165762513, 3421.0, 3458.775179613325, 3471.1669199392154, 3505.333986542557, 5798.130280457934, 5841.707914604274, 5843.1527428522895, 5847.79532716355, 5850.034744267202, 5849.327327357355, 5870.383609720971, 5878.706064696769, 5888.646681199131, 5895.145203945436, 5276.050310944029, 5298.470152984709, 5299.735013249341, 5333.986053110084, 5417.160340147283, 5192.480751924815, 5214.296070030536, 5396.271503347057, 5404.7838086476495, 5407.432219616942, 5214.061468935609, 5270.710110943896, 5279.313689220415, 5299.152135658295, 5305.708186049766, 5368.0605893968395, 5371.81198658049, 5382.138857782736, 5384.326959130109, 5386.030514507387, 5117.82087626935, 5250.921236181461, 5291.947080529184, 5360.59795515334, 5366.060939335601, 5630.014747419185, 5717.857053573653, 5727.427257274281, 5824.767009319622, 5825.854353641193, 6224.782132625336, 6237.896340400951, 6253.093301471306, 6259.812205633838, 6266.780662676784, 6279.497640188774, 6284.654344011124, 6288.213973253343, 6295.338989406138, 6316.178896743394, 5647.407022262644, 5697.1169468732, 5782.0, 5853.326867410217, 5856.355800861902, 6439.613623182624, 6444.613323200623, 6445.452136568444, 6463.57986730861, 6465.806025819233, 6122.836661034389, 6184.257889053342, 6193.1329613854095, 6194.5044396448175, 6198.256209254918, 6087.482563982023, 6124.326324104345, 6168.845778855564, 6230.59501132425, 6293.213348331458, 6239.906401403982, 6249.125122482856, 6262.5929314594405, 6269.65516896566, 6275.843103922439, 6041.305249896231, 6046.879062418727, 6061.605995610271, 6125.459783291287, 6169.0, 4772.570468657837, 4816.20532612115, 4830.0, 4836.371271734652, 4853.927191092136, 4190.643795277375, 4285.37862010013, 4296.334068219431, 4299.699021068495, 4339.5443478434945, 5822.097574875901, 5866.794662186803, 5883.0, 5964.433378829004, 6012.248468941382, 6252.956436442168, 6298.590591611531, 6305.117283580303, 6307.842303942346, 6309.274433440121, 5952.821415357546, 5956.13636022783, 5957.434043765922, 5962.910556341659, 6003.759849605967, 5143.485651434827, 5185.351831021122, 5195.506426889439, 5224.451432599522, 5262.868428289246, 6501.2523559791225, 6538.934610653928, 6539.803805885831, 6546.0508226307875, 6553.967230163914, 6189.0, 6232.314445411092, 6245.937540624535, 6246.812595622138, 6264.937350626436, 6203.379662033941, 6282.057691346335, 6355.491560675043, 6425.164728585441, 6472.902906456313, 6203.596766210182, 6203.720832562636, 6206.317305096345, 6211.689415529115, 6211.9068213977735, 6307.05394190875, 6314.210723659543, 6316.11574379578, 6316.778912738032, 6320.936790632035, 5862.648241105548, 5956.791512297049, 6039.094135879654, 6127.0, 6133.325334213144, 6109.725062372293, 6110.052941794029, 6116.974953844038, 6122.794608433986, 6126.151269885628, 6065.363136870565, 6092.939070609237, 6094.177286066324, 6095.451409373178, 6124.785632503019, 5918.73365698536, 5958.612690175125, 5978.910316345346, 6038.7282572283475, 6063.969680151571, 5935.020721581063, 5937.020391635505, 5964.731587078505, 5972.701364931823, 5978.4021597839865], "value": 5752.672847032474, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.563457281209, 847.593155285463, 866.5017614871449, 936.9484678342693, 949.2927768812236, 31173.220669483246, 42855.35716964244, 43473.565264347344, 43487.0, 43606.34590481142, 45201.0, 45230.77384613072, 45248.77375613125, 45529.317060244124, 45535.316970245476, 7875.409344299172, 9191.81616367674, 9319.627214911396, 9322.720318390426, 9329.95335023324, 7875.251851074138, 10426.426546539937, 10621.681349559525, 10683.572657093708, 10686.091682207021, 10754.623588174416, 10800.891991080107, 10803.243772935897, 10809.0, 10809.081228095622, 10722.517486713117, 10727.892721072767, 10744.409057501834, 10753.731156721145, 10779.78440431191, 10606.098481629071, 10615.469226538642, 10644.680659580225, 10673.146148308195, 10691.518881650345, 43027.35458968118, 43254.35118473225, 43260.783696081315, 43278.35082473765, 43363.56636433627, 4248.299030659938, 4289.627010607029, 4330.610245077926, 4392.516823149451, 4482.282834746425, 30011.849940750373, 30418.54372184419, 30738.692613073807, 30896.691033089606, 31712.0, 4329.740215587043, 4354.934675979863, 4361.694681372305, 4362.411074504965, 4447.310666846644, 233729.0, 235144.0, 235253.0, 235381.0, 235419.0, 54545.0, 54880.72559637176, 54953.0, 54955.0, 55032.17451738147, 56381.0, 56845.0, 56943.86808725477, 56964.43035569674, 56984.71507642518, 16018.519444416686, 16187.676246475005, 16249.0, 16356.182190890646, 16361.263743131587, 12250.81623775644, 12423.937880310541, 14250.144991300554, 14265.928670356581, 14299.714005719827, 12832.101752877395, 13146.21122732639, 13517.66205844842, 13524.391402386917, 13544.119632223874, 5569.610127291051, 5654.123610840326, 5675.801346952837, 5685.943140568541, 5793.0, 1019.8623185869961, 1032.3547782635853, 1038.2939601071344, 1049.984250236232, 1057.603398725478, 28385.858070709928, 28494.572581411296, 28629.565874966567, 28902.132936011953, 28911.710882891322, 1093.327603523827, 1109.4840898982013, 1110.6890070780194, 1113.0, 1127.3179726265655, 14534.781978270334, 15183.620409489851, 15283.923580382027, 15455.768163477556, 15513.37946482128, 297071.0, 310610.0, 310764.0, 311010.0, 311671.0, 56030.71984640132, 56221.43778562244, 56263.71868140633, 56331.0, 56485.15272270837, 1422.6597247527966, 1471.5070451398688, 1475.0928179169948, 1476.247113971882, 1489.0916540910252, 27060.864695676395, 28571.0, 28674.856625716737, 28780.136595902157, 28827.7117228825, 35507.28985420225, 35676.28647427089, 35681.64318356783, 35722.0, 35823.28353432969, 289815.0, 300863.0, 344025.0, 344175.0, 346121.0, 24932.0, 25241.24276271715, 28638.284042899093, 30161.245968850955, 30413.0, 38136.809315953244, 38496.422553661156, 38618.42072368861, 38790.4181437273, 38875.80562097171, 30838.0, 31271.84364078256, 31447.52828707617, 31476.370472589962, 31636.209094771893, 30406.087817365515, 30898.382032359677, 31045.534316984813, 31127.533087004165, 31648.367032658756, 34832.0, 34886.30227395488, 35233.29533409369, 35440.29119417649, 35569.28861422809, 28685.856570717013, 29061.564076539293, 29162.854185728935, 29374.0, 29654.851725741235, 23252.41868953222, 24072.157474488307, 24274.878625606758, 24298.14956476515, 24543.14099006526], "value": 18699.68992316862, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.2942268905881, 462.5443937721346, 479.5755756155802, 481.4920259126622, 494.0588179518018, 19909.203631854725, 21249.787502124993, 21584.784152158514, 21805.127794888205, 22011.779882201172, 22611.0, 22846.200382986597, 22907.198248061322, 23190.0, 23203.18788842391, 6765.22199947005, 6771.0, 6872.175338959307, 6932.653367331638, 6939.791806245821, 6926.307369263084, 6941.965290173542, 7262.52793568419, 7535.20880307568, 7545.434092443062, 7437.776866693981, 7701.037370328709, 7702.998610180669, 7709.0, 7713.5757533335645, 7563.546187228756, 7754.340881025119, 7789.883151752728, 7805.4145939054515, 7807.570583617899, 7385.778426647209, 7540.019797426355, 7581.431392645519, 7596.696132154707, 7599.202083781179, 25083.87458062698, 25407.364815879566, 25501.107461238953, 25546.872265638554, 25607.0, 3154.7160755532113, 3228.677132286776, 3284.6715328467203, 3288.901332960015, 3328.0348698877297, 16664.916675416545, 16851.831481685273, 16983.405580804614, 17049.318027278892, 17620.647587048188, 2354.693889794316, 2444.7799698027084, 2534.9873250633814, 2545.185540627, 2613.568761154407, 87822.0, 103415.96584034139, 112085.0, 113445.86554134518, 115528.0, 18441.170147343135, 18533.53666158357, 18764.81235187658, 18884.716729249074, 19019.80980190208, 32122.0, 32132.678673213435, 32291.677083229337, 32448.513272300934, 32601.18497037593, 9933.155681766986, 10054.145397641136, 10211.591536338612, 10441.425721585234, 10485.318454300446, 8430.114837941928, 8450.19723126302, 8480.0, 8660.09069047741, 8660.913390866137, 9280.350375473652, 9449.76375590602, 9465.0, 9556.331056826095, 9611.807763844685, 3732.8880133596035, 3798.867039653599, 3838.980805095957, 3920.6079392060565, 3984.7210695251633, 944.1125342178331, 953.585190442146, 963.4412041016194, 971.0386717150112, 975.1223898491414, 19810.306639267557, 21399.89300053521, 21569.67645485319, 21830.126794928026, 21990.340289791333, 943.7168849345306, 953.1231267234161, 964.2767924056957, 968.2399316536485, 977.0717818072859, 11345.376004319676, 11911.761764764657, 11958.521659133712, 12056.87943120575, 12183.208091474025, 655004.0, 662328.0, 663489.0, 684330.0, 685117.0, 110916.0, 112128.4393578027, 113395.0, 114359.0, 116753.41623291999, 1271.8028705550655, 1283.3006011723633, 1301.4143635364314, 1302.4204229117952, 1308.6205000549735, 18000.729989050436, 18166.182521786286, 18512.907435462737, 18556.814431856048, 19117.330893418664, 34700.47949280712, 47954.040919182116, 49105.0, 49135.75432122817, 50089.0, 60501.69749151226, 61489.385106148366, 63309.0, 64609.0, 65058.0, 9893.35693179941, 11235.0, 11682.12384071181, 12956.93521532424, 13027.934860326017, 14464.927675361976, 14490.34793434277, 14628.707425851637, 14678.119312841263, 14772.261386930826, 12732.554360597334, 12845.715428457079, 12880.227186368847, 12911.677208069497, 12940.676483087998, 11215.214934954474, 11218.943905280421, 11267.830982535104, 11287.548498059985, 11366.772664546497, 12559.434825432694, 12629.2422454653, 12879.488499742834, 13080.476780928657, 13613.523526676914, 12003.879961200626, 12139.0, 12214.022878169902, 12221.388930553614, 12230.816537752118, 12045.096617753528, 12083.214591051554, 12273.570425035081, 12472.251664900135, 12544.121911466109], "value": 11622.20059396413, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1422.3101795629125, 1540.3838464614157, 1570.654456019676, 1571.8742500599953, 1583.1134564643794, 9669.274804389672, 11415.942920285397, 11778.293302401851, 12157.39213039349, 12190.329531875743, 11796.11529135314, 11906.702332441693, 11909.821352679695, 11941.164118511708, 11947.283163010205, 2027.0675489274952, 2084.937451876446, 2418.7218469875925, 2461.8030557555444, 2462.187478132221, 2473.4311108445067, 2542.3135753346587, 2552.744725527451, 2552.7574880386333, 2552.7702506774385, 2387.6418537219383, 2412.8431651942656, 2423.236680445663, 2423.3941514621347, 2427.0413186791206, 2439.0731522021697, 2457.8402403843693, 2462.642916777066, 2470.4441500662388, 2470.851748895063, 2351.929442116722, 2352.882355882199, 2355.5406695694346, 2359.2214569192297, 2393.210240620591, 11712.238704484182, 11799.882001179963, 11804.822927656092, 11814.527418903233, 11824.527018919232, 5760.481556659879, 5906.409359064102, 5958.374370691058, 6045.123457098696, 6082.96958515209, 3410.7441941854213, 3548.7515873888838, 3628.4738712886483, 3708.87018954338, 3711.5482601180893, 1354.878060974517, 1373.1212024304452, 1395.4694622604725, 1423.5515812519022, 1469.919154446505, 16327.83672163287, 17678.646427071515, 17703.38038168658, 17721.822781772145, 17739.911300443546, 4760.619150467955, 4818.542238487338, 4910.165271903785, 4936.259561065869, 4937.308776771255, 13010.284434356201, 13118.803217951738, 13132.474701012043, 13136.868631313755, 13139.080264381402, 6117.235345581802, 6117.541184411187, 6119.694015299217, 6121.112438696364, 6131.478824299896, 4557.225271703752, 5088.92366614501, 5669.659820410789, 5697.658140511582, 5732.914006289909, 4137.97931010343, 4141.233871733739, 4179.101493178981, 4229.2175947449205, 4234.153169366139, 1094.0536435982817, 1094.3105843318817, 1099.7305660113163, 1113.059464752283, 1114.0670935926162, 907.3875134284332, 930.2046750028763, 935.6257497001254, 940.1632547033146, 949.9097585729345, 7488.176300606929, 7504.099508059068, 7504.662290196954, 7536.773896783106, 7681.193474685189, 1051.4427353502656, 1052.3370276725614, 1058.364981011387, 1059.7403636109202, 1103.2938919091787, 4790.209615413488, 4831.51684831514, 4923.409190897115, 5073.086844368049, 5078.390593128848, 14181.220032898289, 14299.57101286963, 15081.47214847497, 15098.54704358871, 15237.3905043797, 14361.712765744836, 14531.92734036323, 14846.851531484763, 14892.329845156994, 14909.0, 10825.945870270598, 10999.670009899715, 11017.394043327695, 11017.889821101846, 11033.117350612092, 7142.250063743232, 7284.781456556312, 7637.159912409519, 7655.6937722490475, 7776.105747839071, 13052.934735326262, 13189.40647670838, 13215.207087574776, 13219.867801321863, 13249.933750331187, 13258.602241932758, 13386.062975592005, 13509.324533773075, 13606.727865442437, 13668.93165534166, 10081.949590252001, 10160.441175735405, 10176.592936282466, 10258.640947566799, 10282.845757313797, 11062.502187401426, 11127.165462590176, 11167.832482512931, 11198.21612487118, 11331.490082946122, 9469.479178644966, 9517.714468565953, 9621.56702948355, 9671.274654401088, 9915.058069483388, 9096.454212747256, 9116.817663646823, 9133.588988495401, 9164.587593558439, 9310.581023854078, 8615.483071015758, 8768.342374321825, 8768.956155219183, 8777.42946708462, 8779.517126557845, 5996.910046349221, 6039.758409663565, 6130.2030736004035, 6152.230971128609, 6237.064440333805, 6018.759249630141, 6192.566520343533, 6201.0, 6314.084457753692, 6314.715837787218], "value": 5482.715116463757, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.843683618643, 425.9212045771533, 439.936209249659, 446.851591410076, 449.9640028797696, 5120.718360490171, 5324.376065300082, 5382.192671099337, 5382.74344021867, 5400.270963419937, 5185.274061631365, 5273.604479664022, 5280.947190528093, 5289.100852855015, 5294.549963253118, 4359.629431498326, 4445.733256004634, 4454.799534020961, 4480.843170489017, 4494.752788596617, 4450.4881938577155, 4455.309427038814, 4466.888327791815, 4470.307102399133, 4475.351074094255, 4465.084657645175, 4466.486354069275, 4469.150861336336, 4470.530594287602, 4501.414816073906, 4374.387585737999, 4412.205802955466, 4441.244988351988, 4480.081583275437, 4519.52544982777, 4143.316352801784, 4157.688173386978, 4165.354370072643, 4190.55999120091, 4198.8110535026, 5173.32746742921, 5185.118529849935, 5212.400573934008, 5215.295935048758, 5228.137357336035, 4919.0, 5140.820071297487, 5157.200633901752, 5162.5095615916425, 5242.711650859202, 5513.917291240595, 5563.887222555505, 5575.972120139373, 5578.944210557883, 5597.720113994284, 4663.696859704109, 4670.416197975253, 4704.388429504143, 4716.198246298138, 4746.0, 5545.77816887324, 5819.330776960619, 5867.242396073839, 5912.6156799808305, 5919.437653422875, 4787.976060119677, 4805.399325084364, 4831.758412079382, 4833.685810422311, 4885.316055752198, 4955.578775804025, 4957.578605818546, 4959.90080198394, 4962.851114466572, 4965.237340744056, 5002.349694539686, 5155.432902380735, 5182.689038657692, 5204.869878253074, 5233.932596624312, 5562.860928476821, 5566.248556444909, 5572.414896435897, 5583.274174357308, 5593.524550413179, 1635.331961399366, 1660.5682522544225, 1676.2708221923501, 1688.9493315200564, 1696.2366934879353, 1248.6242924051005, 1298.8701129887124, 1310.9184922439026, 1312.4553310376277, 1319.7624427603123, 5353.57171426285, 5376.763344430803, 5399.515133338285, 5414.187871819258, 5456.945430545644, 5643.858903527444, 5649.4633009864, 5706.4864162225595, 5723.828285151369, 5731.3379120054715, 5097.821576244888, 5407.187932507652, 5451.781928722886, 5480.0, 5506.889862202734, 3008.247938015496, 3008.7964814074107, 3016.3967206558336, 3020.9395812083635, 3021.56187352837, 5729.684867332254, 5893.793717219876, 5901.29184497863, 5922.615030023035, 5937.33754548727, 5520.889582208413, 5731.369549349568, 5792.304923409218, 5807.5644326675665, 5813.82558523245, 5726.971365143231, 5841.795537156264, 5871.970640146943, 5892.0, 5926.407359264125, 5691.971540142273, 5696.401877802937, 5699.373068962328, 5728.427157284238, 5729.942700572941, 5278.340207474066, 5426.647267927572, 5557.44425557441, 5586.469285417878, 5610.803621873215, 5869.325027621878, 5872.55955803325, 5884.852878678067, 5899.616524925866, 5915.0, 4571.405717256735, 4730.385049943486, 4739.743967848592, 4750.928736068892, 4794.208955522439, 5663.546916246608, 5705.429457054262, 5705.486506214461, 5710.5717071219, 5713.285839270091, 5562.415946325739, 5565.944340556543, 5580.218769372373, 5637.830865074055, 5652.943470565407, 5313.2561441397465, 5592.328920529562, 5605.1872478491205, 5616.747246373841, 5646.971765141148, 5594.580406469449, 5596.804111856066, 5599.580031497572, 5604.0, 5632.85917852057, 5385.38068122171, 5664.065429204135, 5665.461781130786, 5676.375598684224, 5690.041178587194, 5359.705216212989, 5388.949154914911, 5469.507744303032, 5481.561475082063, 5510.669359838423], "value": 4496.220803915905, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [839.8278352937648, 843.7763992541974, 889.8487257166281, 899.105390136814, 902.5532361481064, 17473.825261747377, 18977.146028428753, 18996.145173467194, 19038.61922761544, 19049.61900761984, 17237.82762172378, 17398.47804565862, 17416.129193540302, 17423.564410889736, 17455.0, 905.5245995852181, 936.148105224246, 943.556528431636, 947.673052796784, 948.7770373962148, 925.1396201532572, 925.6713866577369, 926.4533924984258, 926.735880274122, 926.879505664266, 993.920486361093, 994.2990191914682, 995.343073571443, 996.3573495095659, 996.4419924842075, 985.8816941966938, 989.6387818446251, 989.6931951095147, 989.7624570103123, 990.0347161517535, 722.8951801988683, 725.7459889038847, 729.8868675355328, 729.956202627844, 730.2916171313817, 18961.91836367819, 19127.13927873249, 19411.22355105794, 19504.0, 19864.503387415287, 17646.558836029075, 17704.291828326852, 17739.73390399145, 17757.911210443996, 17960.0, 13581.253031083286, 13643.454261829515, 13668.52160174389, 13689.86310136896, 13770.173789572658, 4196.181744559812, 4210.431591735138, 4244.681648876316, 4250.149970006011, 4345.630621397185, 53068.46931530674, 55208.44791552114, 76778.61610691967, 86846.39460816186, 96051.0, 13684.384202710902, 13946.232957187249, 13979.370928308048, 14009.369578368998, 14319.284035798168, 26379.736202638112, 26459.867700661758, 26673.866630666722, 26754.732452675613, 27009.054683085997, 9566.330356874952, 9639.228861691048, 9788.168005719594, 9862.950685246671, 9874.950625246827, 5086.701751942466, 5238.397584277857, 5256.894862102813, 5316.388615309212, 5323.441038690959, 3836.4245363195746, 3932.8230229639735, 3947.684185265172, 3958.5645578986287, 4008.018035581243, 648.6540428610651, 649.250116115887, 655.1712084213474, 664.7540410048313, 665.2449469851656, 800.8358286551243, 817.2399668308411, 832.0930186097183, 849.9107593702696, 856.9485830850169, 10990.340579565092, 11533.423328833525, 11598.304101753922, 11745.530178792753, 11931.880681193077, 817.587118505163, 830.543201239316, 838.8867502887155, 851.1063383447356, 856.5674334461057, 5193.896122077537, 5499.279288235018, 5561.888762224733, 5664.943350566524, 5686.545076393879, 83855.58072209722, 87128.56435717907, 87441.0, 88777.55611221903, 90886.54556727174, 43376.0, 50062.0, 58898.70550647219, 74595.0, 79463.60268198622, 61332.69333653303, 69398.0, 69709.0, 70741.64629176924, 73900.63049684717, 11786.233894796811, 12900.096993210385, 12994.740105198032, 13012.479500819862, 13084.934575327063, 47391.0, 48032.759836200596, 48060.519394805604, 48585.0, 48716.512834872614, 54487.455125448236, 54738.0, 55453.722731386086, 55647.72176139093, 57360.42639573551, 13960.2321872298, 14131.434742610181, 14866.85133148698, 15046.097234165985, 16110.0, 14662.63343416423, 15105.622359441102, 16199.109049002418, 17502.299908003537, 17617.82382176162, 15828.68342633164, 15892.0, 16092.436764713178, 16145.0, 17389.21748521345, 14608.926955365156, 14662.486812961495, 14924.253787310374, 15163.696726065196, 16292.42976495817, 15286.541403757905, 15558.766618500958, 15709.607259818597, 15823.129727865078, 16630.168491575616, 10415.791684166426, 10601.946990265, 10694.946525267324, 10732.14142868553, 11358.886411136113, 12502.687432814253, 12886.548970786353, 13044.934775326063, 13186.868131318564, 13451.125676831367], "value": 8242.808274216872, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1454.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 113.0, 112.0, 112.0, 113.0, 112.0, 112.0, 112.0, 112.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 113.0, 115.0, 153.0, 115.0, 117.0, 115.0, 117.0, 140.0, 115.0], "value": 124.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 161.0, 159.0, 160.0, 161.0, 161.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 103.0, 76.0, 76.0, 75.0, 75.0, 96.0, 75.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 105.0, 82.0, 82.0, 82.0, 94.0, 81.0, 82.0, 105.0], "value": 87.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 93.0, 93.0, 94.0, 93.0, 94.0, 93.0, 94.0], "value": 93.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 56.0, 55.0, 55.0, 55.0, 58.0, 56.0, 55.0, 55.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 52.0, 54.0, 53.0, 53.0, 54.0, 53.0, 53.0, 52.0], "value": 52.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 84.0, 79.0, 79.0, 81.0, 79.0, 78.0, 84.0, 79.0, 92.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 121.0, 129.0, 121.0, 120.0, 117.0, 138.0, 132.0, 129.0, 130.0], "value": 126.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 165.0, 159.0, 160.0, 160.0, 162.0, 168.0, 163.0, 158.0], "value": 161.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 60.0, 60.0, 59.0, 59.0, 59.0, 60.0, 66.0, 58.0], "value": 60.4, "unit": "score"}]}]} 04:10:15 INFO - Return code: 0 04:10:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:10:15 INFO - # TBPL SUCCESS # 04:10:15 INFO - Running post-action listener: _resource_record_post_action 04:10:15 INFO - [mozharness: 2017-05-29 11:10:15.793895Z] Finished run-tests step (success) 04:10:15 INFO - Running post-run listener: _resource_record_post_run 04:10:15 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 671744; Write bytes: 329764864; Read time: 916; Write time: 382752 04:10:15 INFO - TinderboxPrint: CPU usage
13.1% 04:10:15 INFO - TinderboxPrint: I/O read bytes / time
671,744 / 916 04:10:15 INFO - TinderboxPrint: I/O write bytes / time
329,764,864 / 382,752 04:10:15 INFO - TinderboxPrint: CPU idle
8,294.2 (86.9%) 04:10:15 INFO - TinderboxPrint: CPU user
1,199.0 (12.6%) 04:10:15 INFO - TinderboxPrint: Swap in / out
0 / 0 04:10:15 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 7483392; Read time: 0; Write time: 2924 04:10:15 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 667648; Write bytes: 322281472; Read time: 896; Write time: 379828 04:10:16 INFO - Running post-run listener: _upload_blobber_files 04:10:16 INFO - Blob upload gear active. 04:10:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:10:16 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 04:10:16 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'] 04:10:16 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 04:10:16 INFO - (blobuploader) - INFO - Open directory for files ... 04:10:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:10:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:10:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:10:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:10:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:10:23 INFO - (blobuploader) - INFO - Done attempting. 04:10:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:10:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:10:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:10:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:10:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:10:24 INFO - (blobuploader) - INFO - Done attempting. 04:10:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:10:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:10:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:10:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:10:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:10:25 INFO - (blobuploader) - INFO - Done attempting. 04:10:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:10:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:10:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:10:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:10:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:10:25 INFO - (blobuploader) - INFO - Done attempting. 04:10:25 INFO - (blobuploader) - INFO - Iteration through files over. 04:10:25 INFO - Return code: 0 04:10:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:10:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:10:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/47507641510a6f19d1f95f7acd2745c8fbd5879ebcd360a69ec3ef9c6197b901b38ca513b6ebea68d0548d40b6c74154faa48d8f8a8800e8c0c090b0085feb87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2b6f0e9c05d3f47269292a68f737a885b02c7bbabe1380dfbf083b4d27c792a4881a773fbd5225a9207a43a4a5ca07d6465d2602394a28e9ac4d3b9c67a0a79e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4d160272e4acf53104afce5350d3a90baf6be65d29337f93e663549e064427e839d3955c5f3c21eaa8c49cf43af388d1d48acfdd28da0f66247641567b451dde", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7a258abb68387446fb95984aa576c366d444bccd2b4a27b4b9f82d738273b3cb9ef1b27b7f673cd7e2440944e3fa9fc45287266b4b3add19ee700e9f1324ce05"} 04:10:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:10:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:10:25 INFO - Contents: 04:10:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/47507641510a6f19d1f95f7acd2745c8fbd5879ebcd360a69ec3ef9c6197b901b38ca513b6ebea68d0548d40b6c74154faa48d8f8a8800e8c0c090b0085feb87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2b6f0e9c05d3f47269292a68f737a885b02c7bbabe1380dfbf083b4d27c792a4881a773fbd5225a9207a43a4a5ca07d6465d2602394a28e9ac4d3b9c67a0a79e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4d160272e4acf53104afce5350d3a90baf6be65d29337f93e663549e064427e839d3955c5f3c21eaa8c49cf43af388d1d48acfdd28da0f66247641567b451dde", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7a258abb68387446fb95984aa576c366d444bccd2b4a27b4b9f82d738273b3cb9ef1b27b7f673cd7e2440944e3fa9fc45287266b4b3add19ee700e9f1324ce05"} 04:10:25 INFO - Running post-run listener: copy_logs_to_upload_dir 04:10:25 INFO - Copying logs to upload dir... 04:10:25 INFO - mkdir: /builds/slave/test/build/upload/logs 04:10:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.767158 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-05-29 04:10:25.818613) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 04:10:25.822521) ========= 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=af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/47507641510a6f19d1f95f7acd2745c8fbd5879ebcd360a69ec3ef9c6197b901b38ca513b6ebea68d0548d40b6c74154faa48d8f8a8800e8c0c090b0085feb87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2b6f0e9c05d3f47269292a68f737a885b02c7bbabe1380dfbf083b4d27c792a4881a773fbd5225a9207a43a4a5ca07d6465d2602394a28e9ac4d3b9c67a0a79e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4d160272e4acf53104afce5350d3a90baf6be65d29337f93e663549e064427e839d3955c5f3c21eaa8c49cf43af388d1d48acfdd28da0f66247641567b451dde", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7a258abb68387446fb95984aa576c366d444bccd2b4a27b4b9f82d738273b3cb9ef1b27b7f673cd7e2440944e3fa9fc45287266b4b3add19ee700e9f1324ce05"} build_url:https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011245 build_url: 'https://queue.taskcluster.net/v1/task/ZZEgqrGLS5ecJZxjaL_IWw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/47507641510a6f19d1f95f7acd2745c8fbd5879ebcd360a69ec3ef9c6197b901b38ca513b6ebea68d0548d40b6c74154faa48d8f8a8800e8c0c090b0085feb87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2b6f0e9c05d3f47269292a68f737a885b02c7bbabe1380dfbf083b4d27c792a4881a773fbd5225a9207a43a4a5ca07d6465d2602394a28e9ac4d3b9c67a0a79e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4d160272e4acf53104afce5350d3a90baf6be65d29337f93e663549e064427e839d3955c5f3c21eaa8c49cf43af388d1d48acfdd28da0f66247641567b451dde", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/7a258abb68387446fb95984aa576c366d444bccd2b4a27b4b9f82d738273b3cb9ef1b27b7f673cd7e2440944e3fa9fc45287266b4b3add19ee700e9f1324ce05"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 04:10:25.846844) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:10:25.847181) ========= 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=af724c981e2a0f424dbabf99000001e7-1496053151.304826-977980008 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004451 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:10:25.930900) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 04:10:25.931228) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 04:10:25.931533) ========= ========= Total master_lag: 0.18 =========